{"level":"warn","ts":"2026-05-06T00:49:33.764783Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T00:49:33.76489Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.90: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.90:2380","--initial-cluster=instance=https://199.19.213.90:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.90:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.90:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-06T00:49:33.764988Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T00:49:33.765001Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-05-06T00:49:33.765021Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-06T00:49:33.768015Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"]} {"level":"info","ts":"2026-05-06T00:49:33.768334Z","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.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.90:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-06T00:49:33.773532Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.73004ms"} {"level":"info","ts":"2026-05-06T00:49:33.780058Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b21fdc9c735cc27","cluster-id":"77163f3b44f8f1db"} {"level":"info","ts":"2026-05-06T00:49:33.780152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T00:49:33.780181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 0"} {"level":"info","ts":"2026-05-06T00:49:33.780196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b21fdc9c735cc27 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T00:49:33.780208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 1"} {"level":"info","ts":"2026-05-06T00:49:33.780255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"warn","ts":"2026-05-06T00:49:33.789545Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T00:49:33.79252Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T00:49:33.793925Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-06T00:49:33.796231Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b21fdc9c735cc27","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T00:49:33.796468Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-06T00:49:33.799232Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-06T00:49:33.799257Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-06T00:49:33.799731Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b21fdc9c735cc27","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T00:49:33.800861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"info","ts":"2026-05-06T00:49:33.80178Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","added-peer-id":"2b21fdc9c735cc27","added-peer-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-05-06T00:49:33.804251Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-06T00:49:33.804485Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b21fdc9c735cc27","initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T00:49:33.804484Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-05-06T00:49:33.804665Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-05-06T00:49:33.804539Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T00:49:34.181297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T00:49:34.182049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T00:49:34.182136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgPreVoteResp from 2b21fdc9c735cc27 at term 1"} {"level":"info","ts":"2026-05-06T00:49:34.182156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became candidate at term 2"} {"level":"info","ts":"2026-05-06T00:49:34.182168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgVoteResp from 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-05-06T00:49:34.18218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became leader at term 2"} {"level":"info","ts":"2026-05-06T00:49:34.182192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b21fdc9c735cc27 elected leader 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-05-06T00:49:34.231938Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:49:34.233123Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:49:34.233151Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:49:34.233499Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b21fdc9c735cc27","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.90:2379]}","request-path":"/0/members/2b21fdc9c735cc27/attributes","cluster-id":"77163f3b44f8f1db","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T00:49:34.234823Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T00:49:34.236589Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T00:49:34.235291Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.90:2379"} {"level":"info","ts":"2026-05-06T00:49:34.236502Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T00:49:34.242469Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:49:34.242872Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:49:34.242903Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-06T00:49:36.298427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167191671,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:49:36.799442Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167191671,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:49:37.300378Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167191671,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:49:37.317165Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.526367834s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:49:37.317744Z","caller":"traceutil/trace.go:171","msg":"trace[1934010335] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"1.527039292s","start":"2026-05-06T00:49:35.79068Z","end":"2026-05-06T00:49:37.317719Z","steps":["trace[1934010335] 'process raft request' (duration: 1.526759755s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.318318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.790665Z","time spent":"1.527143864s","remote":"127.0.0.1:53988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:37.788772Z","caller":"traceutil/trace.go:171","msg":"trace[664968353] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.997705406s","start":"2026-05-06T00:49:35.791038Z","end":"2026-05-06T00:49:37.788743Z","steps":["trace[664968353] 'process raft request' (duration: 1.997386818s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:37.788777Z","caller":"traceutil/trace.go:171","msg":"trace[118141853] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.98469768s","start":"2026-05-06T00:49:35.804012Z","end":"2026-05-06T00:49:37.788709Z","steps":["trace[118141853] 'process raft request' (duration: 1.984571897s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.788937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.791028Z","time spent":"1.997821659s","remote":"127.0.0.1:53996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:37.789099Z","caller":"traceutil/trace.go:171","msg":"trace[1148763450] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.915436989s","start":"2026-05-06T00:49:35.87362Z","end":"2026-05-06T00:49:37.789057Z","steps":["trace[1148763450] 'process raft request' (duration: 1.915000917s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.789194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.873608Z","time spent":"1.915555193s","remote":"127.0.0.1:46958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:49:37.789217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.803999Z","time spent":"1.984888825s","remote":"127.0.0.1:54194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:49:37.789512Z","caller":"traceutil/trace.go:171","msg":"trace[1310923120] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:4; }","duration":"1.991936199s","start":"2026-05-06T00:49:35.797556Z","end":"2026-05-06T00:49:37.789492Z","steps":["trace[1310923120] 'read index received' (duration: 1.520031489s)","trace[1310923120] 'applied index is now lower than readState.Index' (duration: 471.9034ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:37.789543Z","caller":"traceutil/trace.go:171","msg":"trace[445835078] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.908501409s","start":"2026-05-06T00:49:35.881029Z","end":"2026-05-06T00:49:37.78953Z","steps":["trace[445835078] 'process raft request' (duration: 1.908399226s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:37.78953Z","caller":"traceutil/trace.go:171","msg":"trace[765546226] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.909474846s","start":"2026-05-06T00:49:35.880012Z","end":"2026-05-06T00:49:37.789487Z","steps":["trace[765546226] 'process raft request' (duration: 1.909328502s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:37.789567Z","caller":"traceutil/trace.go:171","msg":"trace[1657682745] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.909561908s","start":"2026-05-06T00:49:35.879989Z","end":"2026-05-06T00:49:37.789551Z","steps":["trace[1657682745] 'process raft request' (duration: 1.908658984s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.78962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.881018Z","time spent":"1.908557201s","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:49:37.789652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.879966Z","time spent":"1.90965324s","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:49:37.789679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.879999Z","time spent":"1.909614109s","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:49:37.789724Z","caller":"traceutil/trace.go:171","msg":"trace[1583069060] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.909599689s","start":"2026-05-06T00:49:35.880113Z","end":"2026-05-06T00:49:37.789712Z","steps":["trace[1583069060] 'process raft request' (duration: 1.90925982s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:37.78976Z","caller":"traceutil/trace.go:171","msg":"trace[2035598279] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.909564749s","start":"2026-05-06T00:49:35.880167Z","end":"2026-05-06T00:49:37.789731Z","steps":["trace[2035598279] 'process raft request' (duration: 1.909231209s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.789797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.880099Z","time spent":"1.909676002s","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:49:37.789827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.880156Z","time spent":"1.90963537s","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:49:37.789888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.917206669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-06T00:49:37.789872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.986850939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-06T00:49:37.789878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.859496464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T00:49:37.789929Z","caller":"traceutil/trace.go:171","msg":"trace[2024506294] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.91725843s","start":"2026-05-06T00:49:35.872662Z","end":"2026-05-06T00:49:37.78992Z","steps":["trace[2024506294] 'agreement among raft nodes before linearized reading' (duration: 1.917196758s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.789953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.872655Z","time spent":"1.91729217s","remote":"127.0.0.1:54030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-06T00:49:37.789955Z","caller":"traceutil/trace.go:171","msg":"trace[750589022] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"1.859611497s","start":"2026-05-06T00:49:35.930329Z","end":"2026-05-06T00:49:37.78994Z","steps":["trace[750589022] 'agreement among raft nodes before linearized reading' (duration: 1.859501074s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:37.789951Z","caller":"traceutil/trace.go:171","msg":"trace[1714707866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"1.986993303s","start":"2026-05-06T00:49:35.802941Z","end":"2026-05-06T00:49:37.789935Z","steps":["trace[1714707866] 'agreement among raft nodes before linearized reading' (duration: 1.986760757s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.789964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.864241025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-06T00:49:37.789988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.930308Z","time spent":"1.859673759s","remote":"127.0.0.1:54030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-05-06T00:49:37.789997Z","caller":"traceutil/trace.go:171","msg":"trace[1481670862] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.864305487s","start":"2026-05-06T00:49:35.925681Z","end":"2026-05-06T00:49:37.789986Z","steps":["trace[1481670862] 'agreement among raft nodes before linearized reading' (duration: 1.864255305s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.789998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.80293Z","time spent":"1.987057585s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:49:37.790023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.925662Z","time spent":"1.864355887s","remote":"127.0.0.1:54030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-06T00:49:37.790086Z","caller":"traceutil/trace.go:171","msg":"trace[2037825109] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.906255378s","start":"2026-05-06T00:49:35.88381Z","end":"2026-05-06T00:49:37.790066Z","steps":["trace[2037825109] 'process raft request' (duration: 1.905649271s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.790118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.916695585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T00:49:37.790146Z","caller":"traceutil/trace.go:171","msg":"trace[1373819136] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.916721275s","start":"2026-05-06T00:49:35.873415Z","end":"2026-05-06T00:49:37.790136Z","steps":["trace[1373819136] 'agreement among raft nodes before linearized reading' (duration: 1.916675584s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.790168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.873394Z","time spent":"1.916767846s","remote":"127.0.0.1:54014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-05-06T00:49:37.790174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.883781Z","time spent":"1.9063462s","remote":"127.0.0.1:54104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:49:37.790192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.918253237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.90\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T00:49:37.790237Z","caller":"traceutil/trace.go:171","msg":"trace[249021674] range","detail":"{range_begin:/registry/masterleases/199.19.213.90; range_end:; response_count:0; response_revision:11; }","duration":"1.91834862s","start":"2026-05-06T00:49:35.871871Z","end":"2026-05-06T00:49:37.790219Z","steps":["trace[249021674] 'agreement among raft nodes before linearized reading' (duration: 1.918289298s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.790347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.983501796s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-06T00:49:37.790379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.992816462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T00:49:37.790406Z","caller":"traceutil/trace.go:171","msg":"trace[1666118495] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.983573809s","start":"2026-05-06T00:49:35.80681Z","end":"2026-05-06T00:49:37.790384Z","steps":["trace[1666118495] 'agreement among raft nodes before linearized reading' (duration: 1.983493657s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.790439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018274322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T00:49:37.790446Z","caller":"traceutil/trace.go:171","msg":"trace[628059080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"1.992920066s","start":"2026-05-06T00:49:35.797511Z","end":"2026-05-06T00:49:37.790431Z","steps":["trace[628059080] 'agreement among raft nodes before linearized reading' (duration: 1.992826033s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.790449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.806803Z","time spent":"1.983636251s","remote":"127.0.0.1:46908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-06T00:49:37.790463Z","caller":"traceutil/trace.go:171","msg":"trace[699669645] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"1.018333804s","start":"2026-05-06T00:49:36.772121Z","end":"2026-05-06T00:49:37.790455Z","steps":["trace[699669645] 'agreement among raft nodes before linearized reading' (duration: 1.018290643s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.790464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.844941906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-06T00:49:37.790487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:36.772099Z","time spent":"1.018382635s","remote":"127.0.0.1:46844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-05-06T00:49:37.790475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018268092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T00:49:37.7905Z","caller":"traceutil/trace.go:171","msg":"trace[532630552] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.845005708s","start":"2026-05-06T00:49:35.945483Z","end":"2026-05-06T00:49:37.790489Z","steps":["trace[532630552] 'agreement among raft nodes before linearized reading' (duration: 1.844952736s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.790485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.941948847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T00:49:37.790524Z","caller":"traceutil/trace.go:171","msg":"trace[163744542] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"1.018340434s","start":"2026-05-06T00:49:36.772171Z","end":"2026-05-06T00:49:37.790511Z","steps":["trace[163744542] 'agreement among raft nodes before linearized reading' (duration: 1.018247212s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.790541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.945469Z","time spent":"1.845060489s","remote":"127.0.0.1:54030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-06T00:49:37.790564Z","caller":"traceutil/trace.go:171","msg":"trace[739248930] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.942103901s","start":"2026-05-06T00:49:35.84844Z","end":"2026-05-06T00:49:37.790544Z","steps":["trace[739248930] 'agreement among raft nodes before linearized reading' (duration: 1.941953157s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:37.790565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:36.772098Z","time spent":"1.018456077s","remote":"127.0.0.1:46866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-05-06T00:49:37.790599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.848411Z","time spent":"1.942182613s","remote":"127.0.0.1:54030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-06T00:49:37.790488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.797488Z","time spent":"1.992989698s","remote":"127.0.0.1:53934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:49:37.790935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.871844Z","time spent":"1.918429701s","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.19.213.90\" "} {"level":"warn","ts":"2026-05-06T00:49:38.30193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167191683,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:49:38.802871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167191683,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:49:39.16478Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.351543017s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:49:39.196668Z","caller":"traceutil/trace.go:171","msg":"trace[1424200722] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.397641891s","start":"2026-05-06T00:49:37.798999Z","end":"2026-05-06T00:49:39.196641Z","steps":["trace[1424200722] 'process raft request' (duration: 1.366009973s)","trace[1424200722] 'compare' (duration: 31.466014ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:39.196838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.79897Z","time spent":"1.397769274s","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:49:39.456159Z","caller":"traceutil/trace.go:171","msg":"trace[1158184564] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.656953587s","start":"2026-05-06T00:49:37.799164Z","end":"2026-05-06T00:49:39.456118Z","steps":["trace[1158184564] 'process raft request' (duration: 1.654600962s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:39.456283Z","caller":"traceutil/trace.go:171","msg":"trace[2115976081] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.654737586s","start":"2026-05-06T00:49:37.801527Z","end":"2026-05-06T00:49:39.456264Z","steps":["trace[2115976081] 'process raft request' (duration: 1.654579862s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:39.45628Z","caller":"traceutil/trace.go:171","msg":"trace[543598367] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.656993938s","start":"2026-05-06T00:49:37.799232Z","end":"2026-05-06T00:49:39.456226Z","steps":["trace[543598367] 'process raft request' (duration: 1.656843014s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:39.456358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.801513Z","time spent":"1.654811707s","remote":"127.0.0.1:54030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:49:39.456342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.79915Z","time spent":"1.657120451s","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:49:39.456437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.799224Z","time spent":"1.657144132s","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:49:39.456502Z","caller":"traceutil/trace.go:171","msg":"trace[2063520900] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"253.929697ms","start":"2026-05-06T00:49:39.202558Z","end":"2026-05-06T00:49:39.456488Z","steps":["trace[2063520900] 'process raft request' (duration: 253.892346ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:39.456632Z","caller":"traceutil/trace.go:171","msg":"trace[175884514] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"1.654469339s","start":"2026-05-06T00:49:37.80215Z","end":"2026-05-06T00:49:39.45662Z","steps":["trace[175884514] 'process raft request' (duration: 1.654165s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:39.456687Z","caller":"traceutil/trace.go:171","msg":"trace[1748971853] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"1.646261144s","start":"2026-05-06T00:49:37.810415Z","end":"2026-05-06T00:49:39.456676Z","steps":["trace[1748971853] 'process raft request' (duration: 1.645958435s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:39.456695Z","caller":"traceutil/trace.go:171","msg":"trace[324559465] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"1.65488926s","start":"2026-05-06T00:49:37.801795Z","end":"2026-05-06T00:49:39.456684Z","steps":["trace[324559465] 'process raft request' (duration: 1.654420977s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:39.456698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.802136Z","time spent":"1.654536431s","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:49:39.456734Z","caller":"traceutil/trace.go:171","msg":"trace[1652092468] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:14; }","duration":"1.655397704s","start":"2026-05-06T00:49:37.801321Z","end":"2026-05-06T00:49:39.456718Z","steps":["trace[1652092468] 'read index received' (duration: 1.363790142s)","trace[1652092468] 'applied index is now lower than readState.Index' (duration: 291.606322ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:39.456755Z","caller":"traceutil/trace.go:171","msg":"trace[858369406] transaction","detail":"{read_only:false; number_of_response:0; response_revision:19; }","duration":"1.650215561s","start":"2026-05-06T00:49:37.806528Z","end":"2026-05-06T00:49:39.456744Z","steps":["trace[858369406] 'process raft request' (duration: 1.649815s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:39.456784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.801789Z","time spent":"1.654932251s","remote":"127.0.0.1:46958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:49:39.456811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.810387Z","time spent":"1.646328076s","remote":"127.0.0.1:54104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:39.456818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.806493Z","time spent":"1.650295014s","remote":"127.0.0.1:54030","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:49:39.456846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.655513588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T00:49:39.456487Z","caller":"traceutil/trace.go:171","msg":"trace[772358512] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"1.654525099s","start":"2026-05-06T00:49:37.801943Z","end":"2026-05-06T00:49:39.456469Z","steps":["trace[772358512] 'process raft request' (duration: 1.654320054s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:39.456885Z","caller":"traceutil/trace.go:171","msg":"trace[1209117254] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:21; }","duration":"1.655651911s","start":"2026-05-06T00:49:37.801224Z","end":"2026-05-06T00:49:39.456876Z","steps":["trace[1209117254] 'agreement among raft nodes before linearized reading' (duration: 1.655579799s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:39.456911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.801937Z","time spent":"1.654954931s","remote":"127.0.0.1:46866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:49:39.456987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.655290151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"warn","ts":"2026-05-06T00:49:39.456922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.801211Z","time spent":"1.655703512s","remote":"127.0.0.1:46850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-05-06T00:49:39.457056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598681956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-06T00:49:39.457055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.595313385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-06T00:49:39.457082Z","caller":"traceutil/trace.go:171","msg":"trace[1339559477] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:21; }","duration":"1.598705388s","start":"2026-05-06T00:49:37.858368Z","end":"2026-05-06T00:49:39.457074Z","steps":["trace[1339559477] 'agreement among raft nodes before linearized reading' (duration: 1.598668187s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:39.457088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.175433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T00:49:39.457097Z","caller":"traceutil/trace.go:171","msg":"trace[1504238875] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"1.595385367s","start":"2026-05-06T00:49:37.861698Z","end":"2026-05-06T00:49:39.457083Z","steps":["trace[1504238875] 'agreement among raft nodes before linearized reading' (duration: 1.595315515s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:39.457111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.85831Z","time spent":"1.5987909s","remote":"127.0.0.1:54030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-05-06T00:49:39.457128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.861674Z","time spent":"1.595447509s","remote":"127.0.0.1:54030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-06T00:49:39.457129Z","caller":"traceutil/trace.go:171","msg":"trace[2051786820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21; }","duration":"192.214004ms","start":"2026-05-06T00:49:39.264904Z","end":"2026-05-06T00:49:39.457118Z","steps":["trace[2051786820] 'agreement among raft nodes before linearized reading' (duration: 192.152552ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:39.457021Z","caller":"traceutil/trace.go:171","msg":"trace[960292813] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21; }","duration":"1.655331931s","start":"2026-05-06T00:49:37.801681Z","end":"2026-05-06T00:49:39.457013Z","steps":["trace[960292813] 'agreement among raft nodes before linearized reading' (duration: 1.65526864s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:39.457196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452358202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T00:49:39.457273Z","caller":"traceutil/trace.go:171","msg":"trace[1011338023] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:21; }","duration":"1.452479575s","start":"2026-05-06T00:49:38.004774Z","end":"2026-05-06T00:49:39.457254Z","steps":["trace[1011338023] 'agreement among raft nodes before linearized reading' (duration: 1.452362382s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:39.457283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600550918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-05-06T00:49:39.457323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:38.004751Z","time spent":"1.452559867s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-05-06T00:49:39.457336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.654922871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-06T00:49:39.457217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.801676Z","time spent":"1.655529796s","remote":"127.0.0.1:54104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3512,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-06T00:49:39.457368Z","caller":"traceutil/trace.go:171","msg":"trace[1112822591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21; }","duration":"1.654951751s","start":"2026-05-06T00:49:37.802406Z","end":"2026-05-06T00:49:39.457358Z","steps":["trace[1112822591] 'agreement among raft nodes before linearized reading' (duration: 1.654899129s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:39.457378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.652465614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-tz898\" ","response":"range_response_count:1 size:857"} {"level":"warn","ts":"2026-05-06T00:49:39.457401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.802377Z","time spent":"1.655018283s","remote":"127.0.0.1:53934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:49:39.457404Z","caller":"traceutil/trace.go:171","msg":"trace[1749586123] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-tz898; range_end:; response_count:1; response_revision:21; }","duration":"1.652528755s","start":"2026-05-06T00:49:37.804867Z","end":"2026-05-06T00:49:39.457396Z","steps":["trace[1749586123] 'agreement among raft nodes before linearized reading' (duration: 1.652473884s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:39.457439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.804848Z","time spent":"1.652578687s","remote":"127.0.0.1:54194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":879,"request content":"key:\"/registry/certificatesigningrequests/csr-tz898\" "} {"level":"info","ts":"2026-05-06T00:49:39.457324Z","caller":"traceutil/trace.go:171","msg":"trace[2063528873] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"1.60062666s","start":"2026-05-06T00:49:37.856685Z","end":"2026-05-06T00:49:39.457312Z","steps":["trace[2063528873] 'agreement among raft nodes before linearized reading' (duration: 1.600565379s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:39.45749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.856657Z","time spent":"1.600822746s","remote":"127.0.0.1:54030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-06T00:49:39.456215Z","caller":"traceutil/trace.go:171","msg":"trace[832348960] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.656997038s","start":"2026-05-06T00:49:37.79916Z","end":"2026-05-06T00:49:39.456157Z","steps":["trace[832348960] 'process raft request' (duration: 1.656870625s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:39.457712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:37.799108Z","time spent":"1.658477868s","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:49:39.716384Z","caller":"traceutil/trace.go:171","msg":"trace[981919761] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"105.130895ms","start":"2026-05-06T00:49:39.611207Z","end":"2026-05-06T00:49:39.716338Z","steps":["trace[981919761] 'process raft request' (duration: 100.492598ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:39.716587Z","caller":"traceutil/trace.go:171","msg":"trace[1322600725] linearizableReadLoop","detail":"{readStateIndex:57; appliedIndex:54; }","duration":"105.128835ms","start":"2026-05-06T00:49:39.611402Z","end":"2026-05-06T00:49:39.716531Z","steps":["trace[1322600725] 'read index received' (duration: 10.755635ms)","trace[1322600725] 'applied index is now lower than readState.Index' (duration: 94.364989ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:39.717003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.566086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/system-nodes\" ","response":"range_response_count:1 size:1070"} {"level":"info","ts":"2026-05-06T00:49:39.717138Z","caller":"traceutil/trace.go:171","msg":"trace[1585839797] range","detail":"{range_begin:/registry/flowschemas/system-nodes; range_end:; response_count:1; response_revision:53; }","duration":"105.752022ms","start":"2026-05-06T00:49:39.611357Z","end":"2026-05-06T00:49:39.717109Z","steps":["trace[1585839797] 'agreement among raft nodes before linearized reading' (duration: 105.33388ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:39.936832Z","caller":"traceutil/trace.go:171","msg":"trace[1188030240] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"127.888429ms","start":"2026-05-06T00:49:39.808896Z","end":"2026-05-06T00:49:39.936784Z","steps":["trace[1188030240] 'process raft request' (duration: 127.593991ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:39.936921Z","caller":"traceutil/trace.go:171","msg":"trace[1975390081] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"127.021486ms","start":"2026-05-06T00:49:39.809887Z","end":"2026-05-06T00:49:39.936908Z","steps":["trace[1975390081] 'process raft request' (duration: 126.691697ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:39.936854Z","caller":"traceutil/trace.go:171","msg":"trace[1887853478] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"128.769514ms","start":"2026-05-06T00:49:39.808053Z","end":"2026-05-06T00:49:39.936822Z","steps":["trace[1887853478] 'process raft request' (duration: 128.253111ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:40.12517Z","caller":"traceutil/trace.go:171","msg":"trace[1429017359] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:73; }","duration":"146.057567ms","start":"2026-05-06T00:49:39.979076Z","end":"2026-05-06T00:49:40.125133Z","steps":["trace[1429017359] 'read index received' (duration: 104.827007ms)","trace[1429017359] 'applied index is now lower than readState.Index' (duration: 41.2294ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:40.125369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.281373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-06T00:49:40.125414Z","caller":"traceutil/trace.go:171","msg":"trace[1840169270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:72; }","duration":"146.394247ms","start":"2026-05-06T00:49:39.979006Z","end":"2026-05-06T00:49:40.1254Z","steps":["trace[1840169270] 'agreement among raft nodes before linearized reading' (duration: 146.248263ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:40.125332Z","caller":"traceutil/trace.go:171","msg":"trace[940522529] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"151.030664ms","start":"2026-05-06T00:49:39.974262Z","end":"2026-05-06T00:49:40.125293Z","steps":["trace[940522529] 'process raft request' (duration: 150.675634ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:40.125668Z","caller":"traceutil/trace.go:171","msg":"trace[302091171] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"145.452062ms","start":"2026-05-06T00:49:39.980202Z","end":"2026-05-06T00:49:40.125654Z","steps":["trace[302091171] 'process raft request' (duration: 144.877286ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:40.12562Z","caller":"traceutil/trace.go:171","msg":"trace[114333836] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"147.390345ms","start":"2026-05-06T00:49:39.978186Z","end":"2026-05-06T00:49:40.125576Z","steps":["trace[114333836] 'process raft request' (duration: 146.85622ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:40.125845Z","caller":"traceutil/trace.go:171","msg":"trace[496753924] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"153.41892ms","start":"2026-05-06T00:49:39.972396Z","end":"2026-05-06T00:49:40.125815Z","steps":["trace[496753924] 'process raft request' (duration: 111.485719ms)","trace[496753924] 'compare' (duration: 40.747649ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:40.455003Z","caller":"traceutil/trace.go:171","msg":"trace[247344553] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"156.972908ms","start":"2026-05-06T00:49:40.297983Z","end":"2026-05-06T00:49:40.454955Z","steps":["trace[247344553] 'process raft request' (duration: 144.827515ms)","trace[247344553] 'compare' (duration: 11.645599ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:40.455063Z","caller":"traceutil/trace.go:171","msg":"trace[1548862483] linearizableReadLoop","detail":"{readStateIndex:96; appliedIndex:93; }","duration":"137.74293ms","start":"2026-05-06T00:49:40.317299Z","end":"2026-05-06T00:49:40.455042Z","steps":["trace[1548862483] 'read index received' (duration: 125.575405ms)","trace[1548862483] 'applied index is now lower than readState.Index' (duration: 12.165434ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:40.455201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.879404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-06T00:49:40.45587Z","caller":"traceutil/trace.go:171","msg":"trace[1827929160] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:91; }","duration":"138.562332ms","start":"2026-05-06T00:49:40.317284Z","end":"2026-05-06T00:49:40.455847Z","steps":["trace[1827929160] 'agreement among raft nodes before linearized reading' (duration: 137.812602ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:40.45546Z","caller":"traceutil/trace.go:171","msg":"trace[991213174] transaction","detail":"{read_only:false; response_revision:91; number_of_response:1; }","duration":"156.588247ms","start":"2026-05-06T00:49:40.298842Z","end":"2026-05-06T00:49:40.455431Z","steps":["trace[991213174] 'process raft request' (duration: 156.126605ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:40.455499Z","caller":"traceutil/trace.go:171","msg":"trace[816528894] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"156.858214ms","start":"2026-05-06T00:49:40.298622Z","end":"2026-05-06T00:49:40.455481Z","steps":["trace[816528894] 'process raft request' (duration: 156.166345ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:41.270419Z","caller":"traceutil/trace.go:171","msg":"trace[1477981103] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"106.633036ms","start":"2026-05-06T00:49:41.163745Z","end":"2026-05-06T00:49:41.270378Z","steps":["trace[1477981103] 'process raft request' (duration: 57.396955ms)","trace[1477981103] 'compare' (duration: 49.045876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:10.874506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167192677,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:50:11.325884Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.047111543s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:50:11.326388Z","caller":"traceutil/trace.go:171","msg":"trace[2038420937] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.047722539s","start":"2026-05-06T00:50:10.278621Z","end":"2026-05-06T00:50:11.326344Z","steps":["trace[2038420937] 'process raft request' (duration: 1.047512084s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.326644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.278601Z","time spent":"1.047903454s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:50:11.331919Z","caller":"traceutil/trace.go:171","msg":"trace[158053657] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"954.200714ms","start":"2026-05-06T00:50:10.377705Z","end":"2026-05-06T00:50:11.331906Z","steps":["trace[158053657] 'process raft request' (duration: 953.988418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.332036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.377684Z","time spent":"954.300107ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:50:11.332331Z","caller":"traceutil/trace.go:171","msg":"trace[567638180] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:535; }","duration":"958.845851ms","start":"2026-05-06T00:50:10.373457Z","end":"2026-05-06T00:50:11.332302Z","steps":["trace[567638180] 'read index received' (duration: 952.814575ms)","trace[567638180] 'applied index is now lower than readState.Index' (duration: 6.030246ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:11.332445Z","caller":"traceutil/trace.go:171","msg":"trace[1693321304] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"838.54433ms","start":"2026-05-06T00:50:10.493885Z","end":"2026-05-06T00:50:11.332429Z","steps":["trace[1693321304] 'process raft request' (duration: 838.180891ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:11.332492Z","caller":"traceutil/trace.go:171","msg":"trace[1130151539] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"876.113201ms","start":"2026-05-06T00:50:10.456371Z","end":"2026-05-06T00:50:11.332484Z","steps":["trace[1130151539] 'process raft request' (duration: 875.463323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.332556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.456343Z","time spent":"876.182572ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:50:11.332561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.493859Z","time spent":"838.621022ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:50:11.332676Z","caller":"traceutil/trace.go:171","msg":"trace[437315361] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"876.187963ms","start":"2026-05-06T00:50:10.45648Z","end":"2026-05-06T00:50:11.332668Z","steps":["trace[437315361] 'process raft request' (duration: 875.416761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.332715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.456474Z","time spent":"876.224064ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:50:11.332831Z","caller":"traceutil/trace.go:171","msg":"trace[1830281826] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"876.237474ms","start":"2026-05-06T00:50:10.456584Z","end":"2026-05-06T00:50:11.332821Z","steps":["trace[1830281826] 'process raft request' (duration: 875.36195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.332883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.456536Z","time spent":"876.323426ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:50:11.332945Z","caller":"traceutil/trace.go:171","msg":"trace[1132818572] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"837.600024ms","start":"2026-05-06T00:50:10.495334Z","end":"2026-05-06T00:50:11.332934Z","steps":["trace[1132818572] 'process raft request' (duration: 836.768202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.332989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.495306Z","time spent":"837.663256ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:50:11.332998Z","caller":"traceutil/trace.go:171","msg":"trace[1256977829] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"866.506647ms","start":"2026-05-06T00:50:10.466484Z","end":"2026-05-06T00:50:11.332991Z","steps":["trace[1256977829] 'process raft request' (duration: 865.489899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.333029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.466452Z","time spent":"866.562938ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:50:11.333092Z","caller":"traceutil/trace.go:171","msg":"trace[1364294955] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"858.216319ms","start":"2026-05-06T00:50:10.47487Z","end":"2026-05-06T00:50:11.333086Z","steps":["trace[1364294955] 'process raft request' (duration: 857.13159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.333125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.474847Z","time spent":"858.261611ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:50:11.333153Z","caller":"traceutil/trace.go:171","msg":"trace[404561401] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"836.122653ms","start":"2026-05-06T00:50:10.497021Z","end":"2026-05-06T00:50:11.333143Z","steps":["trace[404561401] 'process raft request' (duration: 835.116126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.333193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.497012Z","time spent":"836.165554ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:50:11.333335Z","caller":"traceutil/trace.go:171","msg":"trace[1654247836] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"835.212769ms","start":"2026-05-06T00:50:10.498114Z","end":"2026-05-06T00:50:11.333327Z","steps":["trace[1654247836] 'process raft request' (duration: 834.055118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.333377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.498108Z","time spent":"835.249159ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:50:11.333454Z","caller":"traceutil/trace.go:171","msg":"trace[1488732551] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"779.653304ms","start":"2026-05-06T00:50:10.553784Z","end":"2026-05-06T00:50:11.333438Z","steps":["trace[1488732551] 'process raft request' (duration: 778.41247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.333521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.55375Z","time spent":"779.742356ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:50:11.332358Z","caller":"traceutil/trace.go:171","msg":"trace[1684571245] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"845.050298ms","start":"2026-05-06T00:50:10.487295Z","end":"2026-05-06T00:50:11.332345Z","steps":["trace[1684571245] 'process raft request' (duration: 844.73549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.333677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.487261Z","time spent":"846.378675ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:50:11.333752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.306462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-06T00:50:11.333678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.806165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:11.333786Z","caller":"traceutil/trace.go:171","msg":"trace[1760768504] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"636.393172ms","start":"2026-05-06T00:50:10.697383Z","end":"2026-05-06T00:50:11.333776Z","steps":["trace[1760768504] 'process raft request' (duration: 634.870651ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:11.33381Z","caller":"traceutil/trace.go:171","msg":"trace[1436641669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"265.979018ms","start":"2026-05-06T00:50:11.067803Z","end":"2026-05-06T00:50:11.333782Z","steps":["trace[1436641669] 'agreement among raft nodes before linearized reading' (duration: 265.816324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.333857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.69735Z","time spent":"636.488865ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:50:11.333787Z","caller":"traceutil/trace.go:171","msg":"trace[826593601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"960.372983ms","start":"2026-05-06T00:50:10.3734Z","end":"2026-05-06T00:50:11.333773Z","steps":["trace[826593601] 'agreement among raft nodes before linearized reading' (duration: 960.322582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:11.333958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:10.373378Z","time spent":"960.565998ms","remote":"127.0.0.1:46762","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-05-06T00:50:15.565384Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167192819,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:50:15.909931Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.283412657s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:50:15.911855Z","caller":"traceutil/trace.go:171","msg":"trace[709677535] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"846.727474ms","start":"2026-05-06T00:50:15.065092Z","end":"2026-05-06T00:50:15.91182Z","steps":["trace[709677535] 'read index received' (duration: 845.044378ms)","trace[709677535] 'applied index is now lower than readState.Index' (duration: 1.681996ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:15.911995Z","caller":"traceutil/trace.go:171","msg":"trace[1097316440] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"1.286937173s","start":"2026-05-06T00:50:14.62503Z","end":"2026-05-06T00:50:15.911967Z","steps":["trace[1097316440] 'process raft request' (duration: 1.285965217s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:15.912076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.94371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:15.912144Z","caller":"traceutil/trace.go:171","msg":"trace[479007127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"847.085894ms","start":"2026-05-06T00:50:15.065042Z","end":"2026-05-06T00:50:15.912128Z","steps":["trace[479007127] 'agreement among raft nodes before linearized reading' (duration: 846.918368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:15.912137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:14.624989Z","time spent":"1.287069188s","remote":"127.0.0.1:54016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:50:15.912193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:15.065016Z","time spent":"847.166406ms","remote":"127.0.0.1:53934","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-05-06T00:50:15.912282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.491644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:50:15.912387Z","caller":"traceutil/trace.go:171","msg":"trace[2056249248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"570.672079ms","start":"2026-05-06T00:50:15.34169Z","end":"2026-05-06T00:50:15.912362Z","steps":["trace[2056249248] 'agreement among raft nodes before linearized reading' (duration: 570.365421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:15.912432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:15.341662Z","time spent":"570.762811ms","remote":"127.0.0.1:46762","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-05-06T00:50:15.912288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.318197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:50:15.912519Z","caller":"traceutil/trace.go:171","msg":"trace[120075654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"227.630456ms","start":"2026-05-06T00:50:15.684867Z","end":"2026-05-06T00:50:15.912497Z","steps":["trace[120075654] 'agreement among raft nodes before linearized reading' (duration: 227.196065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:15.913206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.78784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:50:15.913473Z","caller":"traceutil/trace.go:171","msg":"trace[309428810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"521.084929ms","start":"2026-05-06T00:50:15.392357Z","end":"2026-05-06T00:50:15.913442Z","steps":["trace[309428810] 'agreement among raft nodes before linearized reading' (duration: 519.798133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:15.913709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:15.392333Z","time spent":"521.355476ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T00:50:18.080038Z","caller":"traceutil/trace.go:171","msg":"trace[1422419305] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"113.497042ms","start":"2026-05-06T00:50:17.966497Z","end":"2026-05-06T00:50:18.079994Z","steps":["trace[1422419305] 'process raft request' (duration: 112.861525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:20.440251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167192935,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:50:20.713078Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.216717797s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:50:20.713585Z","caller":"traceutil/trace.go:171","msg":"trace[466138239] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.217416736s","start":"2026-05-06T00:50:19.49613Z","end":"2026-05-06T00:50:20.713547Z","steps":["trace[466138239] 'process raft request' (duration: 1.217210991s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:20.713767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:19.496081Z","time spent":"1.21759048s","remote":"127.0.0.1:54118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:50:21.527126Z","caller":"traceutil/trace.go:171","msg":"trace[1284227685] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.77413651s","start":"2026-05-06T00:50:19.752963Z","end":"2026-05-06T00:50:21.5271Z","steps":["trace[1284227685] 'process raft request' (duration: 1.77337139s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:21.527354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:19.752919Z","time spent":"1.774315154s","remote":"127.0.0.1:54016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:50:21.527385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587239594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:50:21.527441Z","caller":"traceutil/trace.go:171","msg":"trace[2139494066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"1.587340606s","start":"2026-05-06T00:50:19.940082Z","end":"2026-05-06T00:50:21.527423Z","steps":["trace[2139494066] 'agreement among raft nodes before linearized reading' (duration: 1.587149691s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:21.527518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:19.940051Z","time spent":"1.58745062s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T00:50:21.527693Z","caller":"traceutil/trace.go:171","msg":"trace[1585181896] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:653; }","duration":"1.586890644s","start":"2026-05-06T00:50:19.940146Z","end":"2026-05-06T00:50:21.527037Z","steps":["trace[1585181896] 'read index received' (duration: 773.225799ms)","trace[1585181896] 'applied index is now lower than readState.Index' (duration: 813.663845ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:21.543228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4766786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:50:21.543251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598836372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:50:21.543312Z","caller":"traceutil/trace.go:171","msg":"trace[2010696840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"1.476822673s","start":"2026-05-06T00:50:20.066469Z","end":"2026-05-06T00:50:21.543292Z","steps":["trace[2010696840] 'agreement among raft nodes before linearized reading' (duration: 1.476626298s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:21.543325Z","caller":"traceutil/trace.go:171","msg":"trace[2082838666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"1.598922275s","start":"2026-05-06T00:50:19.944384Z","end":"2026-05-06T00:50:21.543307Z","steps":["trace[2082838666] 'agreement among raft nodes before linearized reading' (duration: 1.598727741s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:21.543354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:20.066443Z","time spent":"1.476905015s","remote":"127.0.0.1:53940","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-05-06T00:50:21.543576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:19.944301Z","time spent":"1.599264185s","remote":"127.0.0.1:46762","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-05-06T00:50:21.544208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454573831s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-06T00:50:21.544245Z","caller":"traceutil/trace.go:171","msg":"trace[373522362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"1.454611182s","start":"2026-05-06T00:50:20.089624Z","end":"2026-05-06T00:50:21.544235Z","steps":["trace[373522362] 'agreement among raft nodes before linearized reading' (duration: 1.454464458s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:21.544267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:20.089567Z","time spent":"1.454696035s","remote":"127.0.0.1:46762","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-05-06T00:50:21.544838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602653314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:50:21.544878Z","caller":"traceutil/trace.go:171","msg":"trace[1281346912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"1.602693845s","start":"2026-05-06T00:50:19.942174Z","end":"2026-05-06T00:50:21.544868Z","steps":["trace[1281346912] 'agreement among raft nodes before linearized reading' (duration: 1.600892917s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:21.545115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:19.942088Z","time spent":"1.603014904s","remote":"127.0.0.1:46762","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-05-06T00:50:23.399157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.777904371s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:50:23.399581Z","caller":"traceutil/trace.go:171","msg":"trace[572624813] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"1.793836336s","start":"2026-05-06T00:50:21.605725Z","end":"2026-05-06T00:50:23.399562Z","steps":["trace[572624813] 'read index received' (duration: 15.221396ms)","trace[572624813] 'applied index is now lower than readState.Index' (duration: 1.77861355s)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:23.399637Z","caller":"traceutil/trace.go:171","msg":"trace[1930463473] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.803839001s","start":"2026-05-06T00:50:21.595773Z","end":"2026-05-06T00:50:23.399612Z","steps":["trace[1930463473] 'process raft request' (duration: 25.250332ms)","trace[1930463473] 'compare' (duration: 1.776292387s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:23.39974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:21.595738Z","time spent":"1.803961644s","remote":"127.0.0.1:54170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:50:23.399743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.790960248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:23.399854Z","caller":"traceutil/trace.go:171","msg":"trace[1542690262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"1.791114092s","start":"2026-05-06T00:50:21.608731Z","end":"2026-05-06T00:50:23.399846Z","steps":["trace[1542690262] 'agreement among raft nodes before linearized reading' (duration: 1.790927297s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:23.399881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:21.608715Z","time spent":"1.791159943s","remote":"127.0.0.1:53940","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-05-06T00:50:23.399995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.82859911s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.90\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-06T00:50:23.400112Z","caller":"traceutil/trace.go:171","msg":"trace[10656528] range","detail":"{range_begin:/registry/masterleases/199.19.213.90; range_end:; response_count:1; response_revision:638; }","duration":"1.828782985s","start":"2026-05-06T00:50:21.571296Z","end":"2026-05-06T00:50:23.400079Z","steps":["trace[10656528] 'agreement among raft nodes before linearized reading' (duration: 1.828342144s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:23.400293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:21.571269Z","time spent":"1.829003872s","remote":"127.0.0.1:53954","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.90\" "} {"level":"warn","ts":"2026-05-06T00:50:23.902978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167192948,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:50:24.296242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:23.401794Z","time spent":"894.439686ms","remote":"127.0.0.1:53954","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-06T00:50:24.296728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.195649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-06T00:50:24.296779Z","caller":"traceutil/trace.go:171","msg":"trace[757159892] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:638; }","duration":"894.288322ms","start":"2026-05-06T00:50:23.402469Z","end":"2026-05-06T00:50:24.296757Z","steps":["trace[757159892] 'agreement among raft nodes before linearized reading' (duration: 894.168509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:24.296809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:23.402447Z","time spent":"894.355914ms","remote":"127.0.0.1:54146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-05-06T00:50:24.296446Z","caller":"traceutil/trace.go:171","msg":"trace[2108425647] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"893.786268ms","start":"2026-05-06T00:50:23.402512Z","end":"2026-05-06T00:50:24.296298Z","steps":["trace[2108425647] 'read index received' (duration: 858.376606ms)","trace[2108425647] 'applied index is now lower than readState.Index' (duration: 35.405222ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:24.297029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.879605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:50:24.297056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.488283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-06T00:50:24.297085Z","caller":"traceutil/trace.go:171","msg":"trace[265244065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"685.563845ms","start":"2026-05-06T00:50:23.611513Z","end":"2026-05-06T00:50:24.297077Z","steps":["trace[265244065] 'agreement among raft nodes before linearized reading' (duration: 685.441041ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:24.297081Z","caller":"traceutil/trace.go:171","msg":"trace[1425015102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"889.996649ms","start":"2026-05-06T00:50:23.407068Z","end":"2026-05-06T00:50:24.297065Z","steps":["trace[1425015102] 'agreement among raft nodes before linearized reading' (duration: 889.874945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:24.297077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.133673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-06T00:50:24.297104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:23.611485Z","time spent":"685.615056ms","remote":"127.0.0.1:46762","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-05-06T00:50:24.297124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:23.407049Z","time spent":"890.0654ms","remote":"127.0.0.1:53934","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-05-06T00:50:24.297131Z","caller":"traceutil/trace.go:171","msg":"trace[636573033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"685.205965ms","start":"2026-05-06T00:50:23.61191Z","end":"2026-05-06T00:50:24.297116Z","steps":["trace[636573033] 'agreement among raft nodes before linearized reading' (duration: 685.03749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:24.297168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:23.611903Z","time spent":"685.257027ms","remote":"127.0.0.1:46762","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-05-06T00:50:24.297165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.418355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:50:24.297257Z","caller":"traceutil/trace.go:171","msg":"trace[1059751202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"731.566069ms","start":"2026-05-06T00:50:23.56567Z","end":"2026-05-06T00:50:24.297236Z","steps":["trace[1059751202] 'agreement among raft nodes before linearized reading' (duration: 731.290461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:24.297303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:23.565646Z","time spent":"731.647821ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:50:24.297362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.903587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:50:24.2975Z","caller":"traceutil/trace.go:171","msg":"trace[1405640256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"685.085941ms","start":"2026-05-06T00:50:23.612392Z","end":"2026-05-06T00:50:24.297477Z","steps":["trace[1405640256] 'agreement among raft nodes before linearized reading' (duration: 684.751853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:24.297556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:23.612384Z","time spent":"685.161703ms","remote":"127.0.0.1:46762","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-05-06T00:50:24.553059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.749462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:50:24.553225Z","caller":"traceutil/trace.go:171","msg":"trace[1937149848] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"216.70985ms","start":"2026-05-06T00:50:24.336486Z","end":"2026-05-06T00:50:24.553196Z","steps":["trace[1937149848] 'process raft request' (duration: 106.704571ms)","trace[1937149848] 'compare' (duration: 109.529137ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:24.580773Z","caller":"traceutil/trace.go:171","msg":"trace[1080938613] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"207.242969ms","start":"2026-05-06T00:50:24.3735Z","end":"2026-05-06T00:50:24.580743Z","steps":["trace[1080938613] 'process raft request' (duration: 207.028442ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:24.590902Z","caller":"traceutil/trace.go:171","msg":"trace[1157030280] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"100.723791ms","start":"2026-05-06T00:50:24.490124Z","end":"2026-05-06T00:50:24.590848Z","steps":["trace[1157030280] 'process raft request' (duration: 100.449594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:26.41426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.026267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:26.414506Z","caller":"traceutil/trace.go:171","msg":"trace[2071203800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"345.331895ms","start":"2026-05-06T00:50:26.069146Z","end":"2026-05-06T00:50:26.414478Z","steps":["trace[2071203800] 'range keys from in-memory index tree' (duration: 344.893134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:26.414648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:26.069117Z","time spent":"345.51163ms","remote":"127.0.0.1:53934","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-05-06T00:50:26.563176Z","caller":"traceutil/trace.go:171","msg":"trace[1015915051] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"143.612734ms","start":"2026-05-06T00:50:26.419527Z","end":"2026-05-06T00:50:26.56314Z","steps":["trace[1015915051] 'process raft request' (duration: 143.414029ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:26.631026Z","caller":"traceutil/trace.go:171","msg":"trace[1814824129] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:681; }","duration":"125.710327ms","start":"2026-05-06T00:50:26.505277Z","end":"2026-05-06T00:50:26.630988Z","steps":["trace[1814824129] 'read index received' (duration: 57.661365ms)","trace[1814824129] 'applied index is now lower than readState.Index' (duration: 68.047702ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:26.631094Z","caller":"traceutil/trace.go:171","msg":"trace[482629854] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"210.968997ms","start":"2026-05-06T00:50:26.420077Z","end":"2026-05-06T00:50:26.631046Z","steps":["trace[482629854] 'process raft request' (duration: 210.831254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:26.631267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.975515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qsf4s\" ","response":"range_response_count:1 size:17520"} {"level":"info","ts":"2026-05-06T00:50:26.631319Z","caller":"traceutil/trace.go:171","msg":"trace[442919351] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qsf4s; range_end:; response_count:1; response_revision:661; }","duration":"126.098808ms","start":"2026-05-06T00:50:26.50521Z","end":"2026-05-06T00:50:26.631309Z","steps":["trace[442919351] 'agreement among raft nodes before linearized reading' (duration: 125.888701ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:26.631376Z","caller":"traceutil/trace.go:171","msg":"trace[1991292819] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"211.763339ms","start":"2026-05-06T00:50:26.419596Z","end":"2026-05-06T00:50:26.63136Z","steps":["trace[1991292819] 'process raft request' (duration: 201.761603ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:26.631519Z","caller":"traceutil/trace.go:171","msg":"trace[1581533881] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"210.984488ms","start":"2026-05-06T00:50:26.420496Z","end":"2026-05-06T00:50:26.63148Z","steps":["trace[1581533881] 'process raft request' (duration: 210.453304ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:26.839135Z","caller":"traceutil/trace.go:171","msg":"trace[512544974] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"192.236798ms","start":"2026-05-06T00:50:26.646856Z","end":"2026-05-06T00:50:26.839093Z","steps":["trace[512544974] 'process raft request' (duration: 188.143509ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:27.705476Z","caller":"traceutil/trace.go:171","msg":"trace[1182727681] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"171.938938ms","start":"2026-05-06T00:50:27.5335Z","end":"2026-05-06T00:50:27.705439Z","steps":["trace[1182727681] 'process raft request' (duration: 170.984643ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:34.951544Z","caller":"traceutil/trace.go:171","msg":"trace[927060209] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"103.066134ms","start":"2026-05-06T00:50:34.848442Z","end":"2026-05-06T00:50:34.951508Z","steps":["trace[927060209] 'process raft request' (duration: 80.140523ms)","trace[927060209] 'compare' (duration: 22.680265ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:38.152833Z","caller":"traceutil/trace.go:171","msg":"trace[483449000] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"114.441448ms","start":"2026-05-06T00:50:38.03835Z","end":"2026-05-06T00:50:38.152791Z","steps":["trace[483449000] 'process raft request' (duration: 114.115619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:41.140555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193270,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:50:41.503428Z","caller":"traceutil/trace.go:171","msg":"trace[1374753573] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"862.944118ms","start":"2026-05-06T00:50:40.640458Z","end":"2026-05-06T00:50:41.503402Z","steps":["trace[1374753573] 'read index received' (duration: 862.751852ms)","trace[1374753573] 'applied index is now lower than readState.Index' (duration: 190.906µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:41.503485Z","caller":"traceutil/trace.go:171","msg":"trace[340324869] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"878.383039ms","start":"2026-05-06T00:50:40.625065Z","end":"2026-05-06T00:50:41.503448Z","steps":["trace[340324869] 'process raft request' (duration: 878.170402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:41.503851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:40.62505Z","time spent":"878.708867ms","remote":"127.0.0.1:54118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:50:41.504102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.32501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:50:41.5042Z","caller":"traceutil/trace.go:171","msg":"trace[138505165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:800; }","duration":"803.453343ms","start":"2026-05-06T00:50:40.700726Z","end":"2026-05-06T00:50:41.504179Z","steps":["trace[138505165] 'agreement among raft nodes before linearized reading' (duration: 803.248288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:41.504189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.779226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-06T00:50:41.504211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.682267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:50:41.504316Z","caller":"traceutil/trace.go:171","msg":"trace[1636688509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:800; }","duration":"802.93522ms","start":"2026-05-06T00:50:40.701353Z","end":"2026-05-06T00:50:41.504288Z","steps":["trace[1636688509] 'agreement among raft nodes before linearized reading' (duration: 802.599521ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:41.504359Z","caller":"traceutil/trace.go:171","msg":"trace[1704674765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:800; }","duration":"863.881232ms","start":"2026-05-06T00:50:40.640421Z","end":"2026-05-06T00:50:41.504302Z","steps":["trace[1704674765] 'agreement among raft nodes before linearized reading' (duration: 863.180454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:41.504418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:40.701342Z","time spent":"803.061193ms","remote":"127.0.0.1:46762","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-05-06T00:50:41.504458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:40.640409Z","time spent":"864.035906ms","remote":"127.0.0.1:46762","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-05-06T00:50:41.504331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:40.700707Z","time spent":"803.554946ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:50:41.504075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.162469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T00:50:41.504696Z","caller":"traceutil/trace.go:171","msg":"trace[358296855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:800; }","duration":"805.861269ms","start":"2026-05-06T00:50:40.698823Z","end":"2026-05-06T00:50:41.504684Z","steps":["trace[358296855] 'agreement among raft nodes before linearized reading' (duration: 805.073297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:41.504735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:40.698796Z","time spent":"805.93008ms","remote":"127.0.0.1:46762","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-05-06T00:50:59.725317Z","caller":"traceutil/trace.go:171","msg":"trace[91627717] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"131.149572ms","start":"2026-05-06T00:50:59.59412Z","end":"2026-05-06T00:50:59.725269Z","steps":["trace[91627717] 'process raft request' (duration: 99.827509ms)","trace[91627717] 'compare' (duration: 31.144208ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:51:17.074674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.060758ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-06T00:51:18.006715Z","caller":"traceutil/trace.go:171","msg":"trace[1233388084] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"199.966564ms","start":"2026-05-06T00:51:17.806728Z","end":"2026-05-06T00:51:18.006695Z","steps":["trace[1233388084] 'read index received' (duration: 199.703387ms)","trace[1233388084] 'applied index is now lower than readState.Index' (duration: 262.377µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:51:18.006846Z","caller":"traceutil/trace.go:171","msg":"trace[1082754374] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"360.374165ms","start":"2026-05-06T00:51:17.64644Z","end":"2026-05-06T00:51:18.006814Z","steps":["trace[1082754374] 'process raft request' (duration: 360.043196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:18.007034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.359247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-06T00:51:18.007032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:17.646405Z","time spent":"360.5263ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:51:18.007067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.321783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:51:18.00716Z","caller":"traceutil/trace.go:171","msg":"trace[659350741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1019; }","duration":"200.471959ms","start":"2026-05-06T00:51:17.806663Z","end":"2026-05-06T00:51:18.007135Z","steps":["trace[659350741] 'agreement among raft nodes before linearized reading' (duration: 200.208291ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:18.007067Z","caller":"traceutil/trace.go:171","msg":"trace[1062467790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1019; }","duration":"146.461719ms","start":"2026-05-06T00:51:17.860596Z","end":"2026-05-06T00:51:18.007057Z","steps":["trace[1062467790] 'agreement among raft nodes before linearized reading' (duration: 146.291954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:26.991287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193815,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:27.666727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193815,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:28.167437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193815,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:28.667793Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193815,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:29.067971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000226003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-06T00:51:29.068083Z","caller":"traceutil/trace.go:171","msg":"trace[2110646438] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000421357s","start":"2026-05-06T00:51:27.06764Z","end":"2026-05-06T00:51:29.068061Z","steps":["trace[2110646438] 'agreement among raft nodes before linearized reading' (duration: 2.000276064s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:29.068156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:27.06761Z","time spent":"2.00052887s","remote":"127.0.0.1:53934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/06 00:51:29 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T00:51:29.167995Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193815,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:29.668935Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193815,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:30.176448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193815,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:30.318076Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.115333719s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T00:51:30.321074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.476344ms","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-05-06T00:51:30.321192Z","caller":"traceutil/trace.go:171","msg":"trace[568351395] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1047; }","duration":"327.607507ms","start":"2026-05-06T00:51:29.993563Z","end":"2026-05-06T00:51:30.32117Z","steps":["trace[568351395] 'range keys from in-memory index tree' (duration: 327.448432ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:30.321294Z","caller":"traceutil/trace.go:171","msg":"trace[1963098326] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"4.139912386s","start":"2026-05-06T00:51:26.181338Z","end":"2026-05-06T00:51:30.32125Z","steps":["trace[1963098326] 'process raft request' (duration: 4.137142692s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:30.321371Z","caller":"traceutil/trace.go:171","msg":"trace[1312038661] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"4.13969489s","start":"2026-05-06T00:51:26.181667Z","end":"2026-05-06T00:51:30.321362Z","steps":["trace[1312038661] 'process raft request' (duration: 4.139432393s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:30.321296Z","caller":"traceutil/trace.go:171","msg":"trace[683647794] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"4.139429944s","start":"2026-05-06T00:51:26.181818Z","end":"2026-05-06T00:51:30.321248Z","steps":["trace[683647794] 'process raft request' (duration: 4.139386063s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:30.321472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:26.181315Z","time spent":"4.140067719s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:51:30.321507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:26.181657Z","time spent":"4.139748362s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:51:30.321548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:26.181789Z","time spent":"4.13968607s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:51:30.389494Z","caller":"traceutil/trace.go:171","msg":"trace[714033475] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1086; }","duration":"3.898925289s","start":"2026-05-06T00:51:26.490536Z","end":"2026-05-06T00:51:30.389462Z","steps":["trace[714033475] 'read index received' (duration: 3.827916609s)","trace[714033475] 'applied index is now lower than readState.Index' (duration: 71.00763ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:51:30.389609Z","caller":"traceutil/trace.go:171","msg":"trace[1043196816] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"1.683447416s","start":"2026-05-06T00:51:28.706153Z","end":"2026-05-06T00:51:30.3896Z","steps":["trace[1043196816] 'process raft request' (duration: 1.683123847s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:30.389747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:28.706119Z","time spent":"1.683524128s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:51:30.39003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.181300116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-06T00:51:30.390143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.569536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:51:30.390174Z","caller":"traceutil/trace.go:171","msg":"trace[874014589] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1051; }","duration":"518.601336ms","start":"2026-05-06T00:51:29.871562Z","end":"2026-05-06T00:51:30.390163Z","steps":["trace[874014589] 'agreement among raft nodes before linearized reading' (duration: 518.511664ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:30.390147Z","caller":"traceutil/trace.go:171","msg":"trace[1242982892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1051; }","duration":"2.181520812s","start":"2026-05-06T00:51:28.208599Z","end":"2026-05-06T00:51:30.390119Z","steps":["trace[1242982892] 'agreement among raft nodes before linearized reading' (duration: 2.181239115s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:30.390199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:29.871477Z","time spent":"518.71484ms","remote":"127.0.0.1:46850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:51:30.390268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:28.208576Z","time spent":"2.181667197s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T00:51:30.390413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.899893895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-06T00:51:30.390449Z","caller":"traceutil/trace.go:171","msg":"trace[1310899587] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1051; }","duration":"3.899930766s","start":"2026-05-06T00:51:26.49051Z","end":"2026-05-06T00:51:30.39044Z","steps":["trace[1310899587] 'agreement among raft nodes before linearized reading' (duration: 3.899806573s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:30.39047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:26.490438Z","time spent":"3.900027439s","remote":"127.0.0.1:46762","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-05-06T00:51:30.390529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321332906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:51:30.390565Z","caller":"traceutil/trace.go:171","msg":"trace[105677328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1051; }","duration":"1.321413208s","start":"2026-05-06T00:51:29.069141Z","end":"2026-05-06T00:51:30.390554Z","steps":["trace[105677328] 'agreement among raft nodes before linearized reading' (duration: 1.321350047s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:30.390608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:29.069111Z","time spent":"1.3214746s","remote":"127.0.0.1:53940","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-05-06T00:51:30.390801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.603168218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:51:30.390834Z","caller":"traceutil/trace.go:171","msg":"trace[371809090] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1051; }","duration":"1.60324574s","start":"2026-05-06T00:51:28.787577Z","end":"2026-05-06T00:51:30.390823Z","steps":["trace[371809090] 'agreement among raft nodes before linearized reading' (duration: 1.603165868s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:30.390862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:28.787544Z","time spent":"1.603311012s","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:51:38.559083Z","caller":"traceutil/trace.go:171","msg":"trace[1510054961] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"194.170006ms","start":"2026-05-06T00:51:38.364887Z","end":"2026-05-06T00:51:38.559057Z","steps":["trace[1510054961] 'process raft request' (duration: 146.059879ms)","trace[1510054961] 'compare' (duration: 47.850779ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:51:38.559398Z","caller":"traceutil/trace.go:171","msg":"trace[1108456385] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1117; }","duration":"194.421503ms","start":"2026-05-06T00:51:38.364948Z","end":"2026-05-06T00:51:38.55937Z","steps":["trace[1108456385] 'read index received' (duration: 12.388542ms)","trace[1108456385] 'applied index is now lower than readState.Index' (duration: 182.031151ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:51:38.559613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.646959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T00:51:38.559657Z","caller":"traceutil/trace.go:171","msg":"trace[922062257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1078; }","duration":"194.728311ms","start":"2026-05-06T00:51:38.364918Z","end":"2026-05-06T00:51:38.559646Z","steps":["trace[922062257] 'agreement among raft nodes before linearized reading' (duration: 194.574146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:43.079033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193930,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:51:43.32518Z","caller":"traceutil/trace.go:171","msg":"trace[36681135] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"932.523281ms","start":"2026-05-06T00:51:42.392628Z","end":"2026-05-06T00:51:43.325152Z","steps":["trace[36681135] 'process raft request' (duration: 932.357136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:43.325345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:42.392614Z","time spent":"932.653204ms","remote":"127.0.0.1:46762","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-05-06T00:51:43.797159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.924113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:51:43.79734Z","caller":"traceutil/trace.go:171","msg":"trace[2130152995] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"459.427892ms","start":"2026-05-06T00:51:43.337894Z","end":"2026-05-06T00:51:43.797322Z","steps":["trace[2130152995] 'process raft request' (duration: 459.378611ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:43.797323Z","caller":"traceutil/trace.go:171","msg":"trace[1728044045] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"1.221247195s","start":"2026-05-06T00:51:42.576043Z","end":"2026-05-06T00:51:43.797291Z","steps":["trace[1728044045] 'process raft request' (duration: 837.118497ms)","trace[1728044045] 'compare' (duration: 383.627744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:51:43.797396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:43.33787Z","time spent":"459.503314ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:51:43.797473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:42.57602Z","time spent":"1.221366279s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:51:43.797434Z","caller":"traceutil/trace.go:171","msg":"trace[648260721] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1131; }","duration":"1.218970755s","start":"2026-05-06T00:51:42.578422Z","end":"2026-05-06T00:51:43.797393Z","steps":["trace[648260721] 'read index received' (duration: 746.584155ms)","trace[648260721] 'applied index is now lower than readState.Index' (duration: 472.38278ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:51:43.797791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190558406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:51:43.797821Z","caller":"traceutil/trace.go:171","msg":"trace[1232088636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1091; }","duration":"1.190614446s","start":"2026-05-06T00:51:42.607198Z","end":"2026-05-06T00:51:43.797813Z","steps":["trace[1232088636] 'agreement among raft nodes before linearized reading' (duration: 1.190483082s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:43.797816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219395396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-06T00:51:43.797849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:42.607182Z","time spent":"1.190661868s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T00:51:43.797867Z","caller":"traceutil/trace.go:171","msg":"trace[2014775329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1091; }","duration":"1.219484099s","start":"2026-05-06T00:51:42.578365Z","end":"2026-05-06T00:51:43.797849Z","steps":["trace[2014775329] 'agreement among raft nodes before linearized reading' (duration: 1.21914758s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:43.797903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:42.578345Z","time spent":"1.21955218s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:51:43.797971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.067121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-06T00:51:43.797891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.072034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:51:43.798029Z","caller":"traceutil/trace.go:171","msg":"trace[1637192362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1091; }","duration":"730.28198ms","start":"2026-05-06T00:51:43.067739Z","end":"2026-05-06T00:51:43.798021Z","steps":["trace[1637192362] 'agreement among raft nodes before linearized reading' (duration: 730.101345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:43.798062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:43.067717Z","time spent":"730.339ms","remote":"127.0.0.1:53934","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-05-06T00:51:43.798075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16982031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:51:43.798099Z","caller":"traceutil/trace.go:171","msg":"trace[407498346] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1091; }","duration":"1.169881971s","start":"2026-05-06T00:51:42.62821Z","end":"2026-05-06T00:51:43.798092Z","steps":["trace[407498346] 'agreement among raft nodes before linearized reading' (duration: 1.1698417s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:43.798125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:42.628186Z","time spent":"1.169934113s","remote":"127.0.0.1:46922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:51:43.797989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116270008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-06T00:51:43.798179Z","caller":"traceutil/trace.go:171","msg":"trace[481892579] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1091; }","duration":"1.116497513s","start":"2026-05-06T00:51:42.681672Z","end":"2026-05-06T00:51:43.79817Z","steps":["trace[481892579] 'agreement among raft nodes before linearized reading' (duration: 1.116273408s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:43.798211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:42.681642Z","time spent":"1.116559685s","remote":"127.0.0.1:46762","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-05-06T00:51:43.798032Z","caller":"traceutil/trace.go:171","msg":"trace[2071906836] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1091; }","duration":"580.163883ms","start":"2026-05-06T00:51:43.217853Z","end":"2026-05-06T00:51:43.798016Z","steps":["trace[2071906836] 'agreement among raft nodes before linearized reading' (duration: 580.087182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:43.798341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:43.217834Z","time spent":"580.497401ms","remote":"127.0.0.1:46908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:51:43.798038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161992961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T00:51:43.798405Z","caller":"traceutil/trace.go:171","msg":"trace[840059877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1091; }","duration":"1.162380842s","start":"2026-05-06T00:51:42.636011Z","end":"2026-05-06T00:51:43.798392Z","steps":["trace[840059877] 'agreement among raft nodes before linearized reading' (duration: 1.161994051s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:43.798498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:42.635992Z","time spent":"1.162486434s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T00:51:44.005286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.261786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:51:44.005405Z","caller":"traceutil/trace.go:171","msg":"trace[672506829] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"203.211437ms","start":"2026-05-06T00:51:43.802174Z","end":"2026-05-06T00:51:44.005386Z","steps":["trace[672506829] 'process raft request' (duration: 97.739165ms)","trace[672506829] 'compare' (duration: 105.104663ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:51:44.308583Z","caller":"traceutil/trace.go:171","msg":"trace[1648213647] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1135; }","duration":"297.50792ms","start":"2026-05-06T00:51:44.011044Z","end":"2026-05-06T00:51:44.308552Z","steps":["trace[1648213647] 'read index received' (duration: 296.051231ms)","trace[1648213647] 'applied index is now lower than readState.Index' (duration: 1.455039ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:51:44.308937Z","caller":"traceutil/trace.go:171","msg":"trace[1775142339] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"505.819845ms","start":"2026-05-06T00:51:43.803102Z","end":"2026-05-06T00:51:44.308922Z","steps":["trace[1775142339] 'process raft request' (duration: 504.012676ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:44.308954Z","caller":"traceutil/trace.go:171","msg":"trace[419412306] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"505.137876ms","start":"2026-05-06T00:51:43.803804Z","end":"2026-05-06T00:51:44.308942Z","steps":["trace[419412306] 'process raft request' (duration: 504.645013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:44.309007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.966597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:51:44.308996Z","caller":"traceutil/trace.go:171","msg":"trace[1693085954] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"504.9225ms","start":"2026-05-06T00:51:43.804044Z","end":"2026-05-06T00:51:44.308967Z","steps":["trace[1693085954] 'process raft request' (duration: 504.462147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:44.309053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:43.803785Z","time spent":"505.192538ms","remote":"127.0.0.1:46762","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-05-06T00:51:44.309062Z","caller":"traceutil/trace.go:171","msg":"trace[1425886208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"241.08544ms","start":"2026-05-06T00:51:44.067966Z","end":"2026-05-06T00:51:44.309051Z","steps":["trace[1425886208] 'agreement among raft nodes before linearized reading' (duration: 240.989787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:44.309078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.320401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:51:44.309109Z","caller":"traceutil/trace.go:171","msg":"trace[19837172] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1095; }","duration":"107.395233ms","start":"2026-05-06T00:51:44.201703Z","end":"2026-05-06T00:51:44.309098Z","steps":["trace[19837172] 'agreement among raft nodes before linearized reading' (duration: 107.351472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:44.309155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:43.803076Z","time spent":"505.900237ms","remote":"127.0.0.1:46762","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-05-06T00:51:44.30915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:43.804033Z","time spent":"505.039052ms","remote":"127.0.0.1:46762","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-05-06T00:51:44.309018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.952032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:51:44.30926Z","caller":"traceutil/trace.go:171","msg":"trace[1011241016] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1095; }","duration":"298.282961ms","start":"2026-05-06T00:51:44.010968Z","end":"2026-05-06T00:51:44.309251Z","steps":["trace[1011241016] 'agreement among raft nodes before linearized reading' (duration: 297.958273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:46.77481Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:47.276019Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:47.777145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:48.065611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999982773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-06T00:51:48.065736Z","caller":"traceutil/trace.go:171","msg":"trace[251580547] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000148707s","start":"2026-05-06T00:51:46.065562Z","end":"2026-05-06T00:51:48.065711Z","steps":["trace[251580547] 'agreement among raft nodes before linearized reading' (duration: 1.999987822s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:48.066327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:46.065554Z","time spent":"2.000759083s","remote":"127.0.0.1:53934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/06 00:51:48 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T00:51:48.277895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:48.778493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:49.279149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:49.780337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:50.066821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000432525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-06T00:51:50.067002Z","caller":"traceutil/trace.go:171","msg":"trace[503606034] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000725372s","start":"2026-05-06T00:51:48.066247Z","end":"2026-05-06T00:51:50.066973Z","steps":["trace[503606034] 'agreement among raft nodes before linearized reading' (duration: 2.000473076s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:50.067172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:48.06622Z","time spent":"2.000936429s","remote":"127.0.0.1:53940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/06 00:51:50 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T00:51:50.281051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167193944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:51:50.298527Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.493282065s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T00:51:50.360582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.487664ms","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-05-06T00:51:50.360697Z","caller":"traceutil/trace.go:171","msg":"trace[1116373840] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1096; }","duration":"367.613267ms","start":"2026-05-06T00:51:49.993066Z","end":"2026-05-06T00:51:50.36068Z","steps":["trace[1116373840] 'range keys from in-memory index tree' (duration: 367.469722ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:50.361374Z","caller":"traceutil/trace.go:171","msg":"trace[224810015] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"4.556284441s","start":"2026-05-06T00:51:45.805079Z","end":"2026-05-06T00:51:50.361363Z","steps":["trace[224810015] 'process raft request' (duration: 4.493741918s)","trace[224810015] 'compare' (duration: 61.67408ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:51:50.361483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:45.805032Z","time spent":"4.556372173s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:51:50.360061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:48.810058Z","time spent":"1.549996603s","remote":"127.0.0.1:34466","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-06T00:51:50.390961Z","caller":"traceutil/trace.go:171","msg":"trace[65434798] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"4.347393612s","start":"2026-05-06T00:51:46.043528Z","end":"2026-05-06T00:51:50.390922Z","steps":["trace[65434798] 'process raft request' (duration: 4.346882618s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:50.391165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:46.043489Z","time spent":"4.347560386s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:51:50.453677Z","caller":"traceutil/trace.go:171","msg":"trace[1414027320] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1139; }","duration":"4.443888375s","start":"2026-05-06T00:51:46.009755Z","end":"2026-05-06T00:51:50.453643Z","steps":["trace[1414027320] 'read index received' (duration: 4.289071382s)","trace[1414027320] 'applied index is now lower than readState.Index' (duration: 154.814783ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:51:50.453998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.444203622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-06T00:51:50.454092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.904299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:51:50.454119Z","caller":"traceutil/trace.go:171","msg":"trace[1253175923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1098; }","duration":"4.444377776s","start":"2026-05-06T00:51:46.009719Z","end":"2026-05-06T00:51:50.454097Z","steps":["trace[1253175923] 'agreement among raft nodes before linearized reading' (duration: 4.444049038s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:50.454158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.016913994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-06T00:51:50.454182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:46.0097Z","time spent":"4.444471549s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T00:51:50.454178Z","caller":"traceutil/trace.go:171","msg":"trace[948291508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"378.97757ms","start":"2026-05-06T00:51:50.075159Z","end":"2026-05-06T00:51:50.454137Z","steps":["trace[948291508] 'agreement among raft nodes before linearized reading' (duration: 378.886748ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:50.454226Z","caller":"traceutil/trace.go:171","msg":"trace[805926409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1098; }","duration":"3.017011398s","start":"2026-05-06T00:51:47.437187Z","end":"2026-05-06T00:51:50.454198Z","steps":["trace[805926409] 'agreement among raft nodes before linearized reading' (duration: 3.016905324s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:50.454251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:50.075152Z","time spent":"379.091985ms","remote":"127.0.0.1:53934","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-05-06T00:51:50.454272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:47.437157Z","time spent":"3.01710574s","remote":"127.0.0.1:46762","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-05-06T00:51:50.454059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.139477348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:51:50.454343Z","caller":"traceutil/trace.go:171","msg":"trace[1271733214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"4.139834429s","start":"2026-05-06T00:51:46.314485Z","end":"2026-05-06T00:51:50.45432Z","steps":["trace[1271733214] 'agreement among raft nodes before linearized reading' (duration: 4.139381866s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:50.454387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:46.314456Z","time spent":"4.139922091s","remote":"127.0.0.1:46762","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-05-06T00:51:50.454397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.026091284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-06T00:51:50.454456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.027219635s","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-05-06T00:51:50.454496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.678710881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:51:50.454498Z","caller":"traceutil/trace.go:171","msg":"trace[1229187164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1098; }","duration":"4.026259119s","start":"2026-05-06T00:51:46.428216Z","end":"2026-05-06T00:51:50.454475Z","steps":["trace[1229187164] 'agreement among raft nodes before linearized reading' (duration: 4.02594568s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:50.454505Z","caller":"traceutil/trace.go:171","msg":"trace[1340874286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1098; }","duration":"4.027315478s","start":"2026-05-06T00:51:46.427171Z","end":"2026-05-06T00:51:50.454487Z","steps":["trace[1340874286] 'agreement among raft nodes before linearized reading' (duration: 4.027217116s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:50.454539Z","caller":"traceutil/trace.go:171","msg":"trace[1175354041] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1098; }","duration":"3.678802193s","start":"2026-05-06T00:51:46.775724Z","end":"2026-05-06T00:51:50.454526Z","steps":["trace[1175354041] 'agreement among raft nodes before linearized reading' (duration: 3.678742141s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:50.454553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:46.428193Z","time spent":"4.026350901s","remote":"127.0.0.1:46762","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-05-06T00:51:50.454563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:46.427144Z","time spent":"4.027406381s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T00:51:50.454562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.744229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18acd3ac6b6dd824\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-05-06T00:51:50.454224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.621585087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:51:50.454658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041144288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:51:50.454671Z","caller":"traceutil/trace.go:171","msg":"trace[60805337] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1098; }","duration":"2.622181402s","start":"2026-05-06T00:51:47.832471Z","end":"2026-05-06T00:51:50.454653Z","steps":["trace[60805337] 'agreement among raft nodes before linearized reading' (duration: 2.621539845s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:50.454661Z","caller":"traceutil/trace.go:171","msg":"trace[1999713712] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18acd3ac6b6dd824; range_end:; response_count:1; response_revision:1098; }","duration":"383.909332ms","start":"2026-05-06T00:51:50.070729Z","end":"2026-05-06T00:51:50.454639Z","steps":["trace[1999713712] 'agreement among raft nodes before linearized reading' (duration: 383.588334ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:50.454694Z","caller":"traceutil/trace.go:171","msg":"trace[60973248] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1098; }","duration":"1.04123547s","start":"2026-05-06T00:51:49.413447Z","end":"2026-05-06T00:51:50.454683Z","steps":["trace[60973248] 'agreement among raft nodes before linearized reading' (duration: 1.041170078s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:50.454707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:47.832437Z","time spent":"2.622257524s","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:51:50.454575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:46.775696Z","time spent":"3.678871685s","remote":"127.0.0.1:47008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:51:50.454735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:49.413404Z","time spent":"1.041324412s","remote":"127.0.0.1:46830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:51:50.454803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:50.070703Z","time spent":"384.034785ms","remote":"127.0.0.1:54008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18acd3ac6b6dd824\" "} {"level":"info","ts":"2026-05-06T00:51:50.65461Z","caller":"traceutil/trace.go:171","msg":"trace[24811183] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"191.415702ms","start":"2026-05-06T00:51:50.463155Z","end":"2026-05-06T00:51:50.654571Z","steps":["trace[24811183] 'process raft request' (duration: 190.473816ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:50.654961Z","caller":"traceutil/trace.go:171","msg":"trace[532094843] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"191.919715ms","start":"2026-05-06T00:51:50.46175Z","end":"2026-05-06T00:51:50.65367Z","steps":["trace[532094843] 'process raft request' (duration: 191.537015ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:50.655803Z","caller":"traceutil/trace.go:171","msg":"trace[462144280] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"192.098279ms","start":"2026-05-06T00:51:50.463677Z","end":"2026-05-06T00:51:50.655775Z","steps":["trace[462144280] 'process raft request' (duration: 190.815106ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:50.656642Z","caller":"traceutil/trace.go:171","msg":"trace[1662291081] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"192.748358ms","start":"2026-05-06T00:51:50.463861Z","end":"2026-05-06T00:51:50.656609Z","steps":["trace[1662291081] 'process raft request' (duration: 191.651569ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:50.653716Z","caller":"traceutil/trace.go:171","msg":"trace[886756109] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"190.811015ms","start":"2026-05-06T00:51:50.462869Z","end":"2026-05-06T00:51:50.65368Z","steps":["trace[886756109] 'process raft request' (duration: 190.700652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:51.98309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.031865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:51:51.983239Z","caller":"traceutil/trace.go:171","msg":"trace[16857049] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"347.998582ms","start":"2026-05-06T00:51:51.635213Z","end":"2026-05-06T00:51:51.983211Z","steps":["trace[16857049] 'process raft request' (duration: 188.707479ms)","trace[16857049] 'compare' (duration: 158.736118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:51:51.983311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:51:51.635189Z","time spent":"348.091935ms","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:52:01.898157Z","caller":"traceutil/trace.go:171","msg":"trace[1912921811] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"100.747526ms","start":"2026-05-06T00:52:01.79737Z","end":"2026-05-06T00:52:01.898117Z","steps":["trace[1912921811] 'process raft request' (duration: 72.805138ms)","trace[1912921811] 'compare' (duration: 27.778904ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:01.90744Z","caller":"traceutil/trace.go:171","msg":"trace[1334141486] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"102.150943ms","start":"2026-05-06T00:52:01.805259Z","end":"2026-05-06T00:52:01.90741Z","steps":["trace[1334141486] 'process raft request' (duration: 101.890817ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:01.909802Z","caller":"traceutil/trace.go:171","msg":"trace[1803369534] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"101.65345ms","start":"2026-05-06T00:52:01.805709Z","end":"2026-05-06T00:52:01.907363Z","steps":["trace[1803369534] 'process raft request' (duration: 101.599279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:05.269452Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167194163,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:52:05.770043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167194163,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:52:05.877785Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.141845542s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:52:05.878209Z","caller":"traceutil/trace.go:171","msg":"trace[1294003670] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"1.109463836s","start":"2026-05-06T00:52:04.768716Z","end":"2026-05-06T00:52:05.87818Z","steps":["trace[1294003670] 'read index received' (duration: 1.10923654s)","trace[1294003670] 'applied index is now lower than readState.Index' (duration: 226.206µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:05.87862Z","caller":"traceutil/trace.go:171","msg":"trace[1783539642] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"1.142787537s","start":"2026-05-06T00:52:04.735818Z","end":"2026-05-06T00:52:05.878606Z","steps":["trace[1783539642] 'process raft request' (duration: 1.142163731s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:05.878771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:04.735798Z","time spent":"1.14286401s","remote":"127.0.0.1:46762","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-05-06T00:52:05.879073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110373861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:52:05.87911Z","caller":"traceutil/trace.go:171","msg":"trace[307944848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1198; }","duration":"1.110452093s","start":"2026-05-06T00:52:04.768645Z","end":"2026-05-06T00:52:05.879098Z","steps":["trace[307944848] 'agreement among raft nodes before linearized reading' (duration: 1.110327539s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:05.879139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:04.768623Z","time spent":"1.110509234s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:52:05.879302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109118437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:52:05.87933Z","caller":"traceutil/trace.go:171","msg":"trace[1070703332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1198; }","duration":"1.109146127s","start":"2026-05-06T00:52:04.770175Z","end":"2026-05-06T00:52:05.879322Z","steps":["trace[1070703332] 'agreement among raft nodes before linearized reading' (duration: 1.109071375s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:05.879354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:04.770104Z","time spent":"1.10924373s","remote":"127.0.0.1:46762","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-05-06T00:52:05.87958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049047901s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-06T00:52:05.879607Z","caller":"traceutil/trace.go:171","msg":"trace[1465705452] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1198; }","duration":"1.049127082s","start":"2026-05-06T00:52:04.830472Z","end":"2026-05-06T00:52:05.8796Z","steps":["trace[1465705452] 'agreement among raft nodes before linearized reading' (duration: 1.04905093s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:05.879636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:04.830449Z","time spent":"1.049177763s","remote":"127.0.0.1:46762","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-05-06T00:52:05.87974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050738985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:52:05.879768Z","caller":"traceutil/trace.go:171","msg":"trace[957756294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1198; }","duration":"1.050810116s","start":"2026-05-06T00:52:04.828949Z","end":"2026-05-06T00:52:05.87976Z","steps":["trace[957756294] 'agreement among raft nodes before linearized reading' (duration: 1.050736185s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:05.879786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:04.828928Z","time spent":"1.050853798s","remote":"127.0.0.1:46762","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-05-06T00:52:06.567004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167194166,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:52:06.76915Z","caller":"traceutil/trace.go:171","msg":"trace[582031292] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"884.678291ms","start":"2026-05-06T00:52:05.884447Z","end":"2026-05-06T00:52:06.769125Z","steps":["trace[582031292] 'process raft request' (duration: 884.554748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:06.769299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:05.884424Z","time spent":"884.799926ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:52:06.770041Z","caller":"traceutil/trace.go:171","msg":"trace[735376438] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1247; }","duration":"703.591666ms","start":"2026-05-06T00:52:06.066402Z","end":"2026-05-06T00:52:06.769994Z","steps":["trace[735376438] 'read index received' (duration: 702.691031ms)","trace[735376438] 'applied index is now lower than readState.Index' (duration: 900.205µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:06.77008Z","caller":"traceutil/trace.go:171","msg":"trace[1056518809] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"884.888358ms","start":"2026-05-06T00:52:05.885172Z","end":"2026-05-06T00:52:06.77006Z","steps":["trace[1056518809] 'process raft request' (duration: 884.758724ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:06.770125Z","caller":"traceutil/trace.go:171","msg":"trace[635859142] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"884.828036ms","start":"2026-05-06T00:52:05.885292Z","end":"2026-05-06T00:52:06.77012Z","steps":["trace[635859142] 'process raft request' (duration: 884.659502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:06.770182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:05.885283Z","time spent":"884.854587ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:52:06.770274Z","caller":"traceutil/trace.go:171","msg":"trace[564188188] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"325.122839ms","start":"2026-05-06T00:52:06.445146Z","end":"2026-05-06T00:52:06.770269Z","steps":["trace[564188188] 'process raft request' (duration: 324.822911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:06.770304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:06.445123Z","time spent":"325.166841ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:52:06.77034Z","caller":"traceutil/trace.go:171","msg":"trace[2086145210] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"885.232216ms","start":"2026-05-06T00:52:05.885091Z","end":"2026-05-06T00:52:06.770323Z","steps":["trace[2086145210] 'process raft request' (duration: 884.768184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:06.770397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:05.885134Z","time spent":"884.98974ms","remote":"127.0.0.1:46762","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-05-06T00:52:06.770401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.010787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:52:06.770411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:05.885062Z","time spent":"885.313978ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:52:06.77044Z","caller":"traceutil/trace.go:171","msg":"trace[1727414979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"704.093639ms","start":"2026-05-06T00:52:06.066335Z","end":"2026-05-06T00:52:06.770429Z","steps":["trace[1727414979] 'agreement among raft nodes before linearized reading' (duration: 704.038208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:06.770488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:06.066314Z","time spent":"704.156131ms","remote":"127.0.0.1:53934","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-05-06T00:52:06.770606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.851174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:52:06.770629Z","caller":"traceutil/trace.go:171","msg":"trace[1149919754] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1203; }","duration":"166.876666ms","start":"2026-05-06T00:52:06.603747Z","end":"2026-05-06T00:52:06.770624Z","steps":["trace[1149919754] 'agreement among raft nodes before linearized reading' (duration: 166.843854ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:20.082521Z","caller":"traceutil/trace.go:171","msg":"trace[361566211] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"127.01599ms","start":"2026-05-06T00:52:19.95546Z","end":"2026-05-06T00:52:20.082476Z","steps":["trace[361566211] 'process raft request' (duration: 126.797744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:23.267498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:22.904405Z","time spent":"363.085474ms","remote":"127.0.0.1:35032","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-06T00:52:23.268422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.396194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:52:23.268538Z","caller":"traceutil/trace.go:171","msg":"trace[1458973234] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"430.028156ms","start":"2026-05-06T00:52:22.838493Z","end":"2026-05-06T00:52:23.268522Z","steps":["trace[1458973234] 'read index received' (duration: 131.372745ms)","trace[1458973234] 'applied index is now lower than readState.Index' (duration: 298.653451ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:52:23.268737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.258293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:52:23.268705Z","caller":"traceutil/trace.go:171","msg":"trace[181243339] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"435.41275ms","start":"2026-05-06T00:52:22.83322Z","end":"2026-05-06T00:52:23.268633Z","steps":["trace[181243339] 'process raft request' (duration: 136.716597ms)","trace[181243339] 'compare' (duration: 298.040245ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:23.268771Z","caller":"traceutil/trace.go:171","msg":"trace[1375821133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1261; }","duration":"430.322504ms","start":"2026-05-06T00:52:22.83844Z","end":"2026-05-06T00:52:23.268762Z","steps":["trace[1375821133] 'agreement among raft nodes before linearized reading' (duration: 430.16725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:23.268803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:22.838428Z","time spent":"430.368965ms","remote":"127.0.0.1:46762","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-05-06T00:52:23.268856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.166179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-06T00:52:23.268863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.938633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-06T00:52:23.268912Z","caller":"traceutil/trace.go:171","msg":"trace[934207380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1261; }","duration":"418.286152ms","start":"2026-05-06T00:52:22.850606Z","end":"2026-05-06T00:52:23.268892Z","steps":["trace[934207380] 'agreement among raft nodes before linearized reading' (duration: 418.105027ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:23.268942Z","caller":"traceutil/trace.go:171","msg":"trace[901341533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1261; }","duration":"418.052695ms","start":"2026-05-06T00:52:22.850871Z","end":"2026-05-06T00:52:23.268923Z","steps":["trace[901341533] 'agreement among raft nodes before linearized reading' (duration: 417.82535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:23.268953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:22.85058Z","time spent":"418.366874ms","remote":"127.0.0.1:46762","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-05-06T00:52:23.268978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:22.850859Z","time spent":"418.113288ms","remote":"127.0.0.1:46762","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-05-06T00:52:23.268962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:22.833193Z","time spent":"435.614555ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:52:23.269063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.877103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:52:23.269174Z","caller":"traceutil/trace.go:171","msg":"trace[1241667715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1261; }","duration":"420.07916ms","start":"2026-05-06T00:52:22.849071Z","end":"2026-05-06T00:52:23.26915Z","steps":["trace[1241667715] 'agreement among raft nodes before linearized reading' (duration: 419.623288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:23.26925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:22.849041Z","time spent":"420.197873ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T00:52:23.590013Z","caller":"traceutil/trace.go:171","msg":"trace[1271232470] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"316.728255ms","start":"2026-05-06T00:52:23.273255Z","end":"2026-05-06T00:52:23.589983Z","steps":["trace[1271232470] 'process raft request' (duration: 316.54997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:23.590183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:23.273224Z","time spent":"316.87894ms","remote":"127.0.0.1:46762","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-05-06T00:52:23.640094Z","caller":"traceutil/trace.go:171","msg":"trace[147633098] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"362.702925ms","start":"2026-05-06T00:52:23.277357Z","end":"2026-05-06T00:52:23.64006Z","steps":["trace[147633098] 'process raft request' (duration: 362.651113ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:23.640141Z","caller":"traceutil/trace.go:171","msg":"trace[427888490] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"364.484023ms","start":"2026-05-06T00:52:23.275611Z","end":"2026-05-06T00:52:23.640095Z","steps":["trace[427888490] 'process raft request' (duration: 364.155083ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:23.640134Z","caller":"traceutil/trace.go:171","msg":"trace[1494886257] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"364.395131ms","start":"2026-05-06T00:52:23.275698Z","end":"2026-05-06T00:52:23.640093Z","steps":["trace[1494886257] 'process raft request' (duration: 364.262616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:23.640315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:23.277325Z","time spent":"362.86864ms","remote":"127.0.0.1:46762","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-05-06T00:52:23.6404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:23.275585Z","time spent":"364.665758ms","remote":"127.0.0.1:46762","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-05-06T00:52:23.640437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:23.27569Z","time spent":"364.665707ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:52:24.597374Z","caller":"traceutil/trace.go:171","msg":"trace[971152995] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"491.759488ms","start":"2026-05-06T00:52:24.105573Z","end":"2026-05-06T00:52:24.597333Z","steps":["trace[971152995] 'process raft request' (duration: 491.48647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:24.597574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:24.105552Z","time spent":"491.925203ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:52:25.456698Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167194335,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:52:25.912829Z","caller":"traceutil/trace.go:171","msg":"trace[719122428] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"1.305278077s","start":"2026-05-06T00:52:24.607515Z","end":"2026-05-06T00:52:25.912793Z","steps":["trace[719122428] 'process raft request' (duration: 1.305038989s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:25.913052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:24.607489Z","time spent":"1.305448601s","remote":"127.0.0.1:54104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5499,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:52:25.963431Z","caller":"traceutil/trace.go:171","msg":"trace[1397183736] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"1.171865937s","start":"2026-05-06T00:52:24.79154Z","end":"2026-05-06T00:52:25.963406Z","steps":["trace[1397183736] 'process raft request' (duration: 1.171569599s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:25.963588Z","caller":"traceutil/trace.go:171","msg":"trace[1987917629] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1322; }","duration":"1.007469217s","start":"2026-05-06T00:52:24.956079Z","end":"2026-05-06T00:52:25.963548Z","steps":["trace[1987917629] 'read index received' (duration: 956.349449ms)","trace[1987917629] 'applied index is now lower than readState.Index' (duration: 51.115688ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:25.963774Z","caller":"traceutil/trace.go:171","msg":"trace[1196079678] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"686.207382ms","start":"2026-05-06T00:52:25.277528Z","end":"2026-05-06T00:52:25.963735Z","steps":["trace[1196079678] 'process raft request' (duration: 685.818581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:25.963799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:24.791511Z","time spent":"1.172036951s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:52:25.963893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007782626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:52:25.963934Z","caller":"traceutil/trace.go:171","msg":"trace[612508222] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1272; }","duration":"1.007891979s","start":"2026-05-06T00:52:24.956029Z","end":"2026-05-06T00:52:25.963921Z","steps":["trace[612508222] 'agreement among raft nodes before linearized reading' (duration: 1.007760175s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:25.963967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:24.95601Z","time spent":"1.007948351s","remote":"127.0.0.1:46938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:52:25.963976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:25.277495Z","time spent":"686.361866ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:52:25.964154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.153736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-06T00:52:25.9642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.451418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-06T00:52:25.96427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.30834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-06T00:52:25.964295Z","caller":"traceutil/trace.go:171","msg":"trace[747962868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"317.319361ms","start":"2026-05-06T00:52:25.646918Z","end":"2026-05-06T00:52:25.964237Z","steps":["trace[747962868] 'agreement among raft nodes before linearized reading' (duration: 316.990592ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:25.964304Z","caller":"traceutil/trace.go:171","msg":"trace[1154808275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1272; }","duration":"316.593111ms","start":"2026-05-06T00:52:25.647687Z","end":"2026-05-06T00:52:25.96428Z","steps":["trace[1154808275] 'agreement among raft nodes before linearized reading' (duration: 316.283003ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:25.964314Z","caller":"traceutil/trace.go:171","msg":"trace[1147892931] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1272; }","duration":"317.384562ms","start":"2026-05-06T00:52:25.646917Z","end":"2026-05-06T00:52:25.964302Z","steps":["trace[1147892931] 'agreement among raft nodes before linearized reading' (duration: 317.202908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:25.964356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:25.646892Z","time spent":"317.457233ms","remote":"127.0.0.1:46762","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-05-06T00:52:25.964362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:25.647649Z","time spent":"316.704434ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:52:25.964363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:25.646906Z","time spent":"317.446434ms","remote":"127.0.0.1:46762","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-05-06T00:52:25.964457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.246678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:52:25.964524Z","caller":"traceutil/trace.go:171","msg":"trace[1908404140] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1272; }","duration":"342.363641ms","start":"2026-05-06T00:52:25.622144Z","end":"2026-05-06T00:52:25.964508Z","steps":["trace[1908404140] 'agreement among raft nodes before linearized reading' (duration: 342.261038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:25.964549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:25.622125Z","time spent":"342.417762ms","remote":"127.0.0.1:51336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:52:25.964813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.703272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:52:25.964871Z","caller":"traceutil/trace.go:171","msg":"trace[271192402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1272; }","duration":"369.811655ms","start":"2026-05-06T00:52:25.595047Z","end":"2026-05-06T00:52:25.964859Z","steps":["trace[271192402] 'agreement among raft nodes before linearized reading' (duration: 368.83721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:25.964907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:25.595022Z","time spent":"369.875206ms","remote":"127.0.0.1:46762","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-05-06T00:52:26.780823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.698616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-06T00:52:26.780935Z","caller":"traceutil/trace.go:171","msg":"trace[1761471762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1278; }","duration":"177.86919ms","start":"2026-05-06T00:52:26.603033Z","end":"2026-05-06T00:52:26.780902Z","steps":["trace[1761471762] 'range keys from in-memory index tree' (duration: 177.424078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:27.523623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167194349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:52:28.0241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167194349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:52:28.525172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167194349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:52:28.5983Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.819297928s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:52:28.598824Z","caller":"traceutil/trace.go:171","msg":"trace[1073975884] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"1.819908233s","start":"2026-05-06T00:52:26.778887Z","end":"2026-05-06T00:52:28.598795Z","steps":["trace[1073975884] 'process raft request' (duration: 1.819707958s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:28.598967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:26.778862Z","time spent":"1.820038868s","remote":"127.0.0.1:54008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:52:28.616225Z","caller":"traceutil/trace.go:171","msg":"trace[1044552062] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"1.83187582s","start":"2026-05-06T00:52:26.78431Z","end":"2026-05-06T00:52:28.616186Z","steps":["trace[1044552062] 'process raft request' (duration: 1.831557632s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:28.616433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:26.784291Z","time spent":"1.832031885s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:52:28.659845Z","caller":"traceutil/trace.go:171","msg":"trace[760272451] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1331; }","duration":"1.637193286s","start":"2026-05-06T00:52:27.022618Z","end":"2026-05-06T00:52:28.659811Z","steps":["trace[760272451] 'read index received' (duration: 1.575990371s)","trace[760272451] 'applied index is now lower than readState.Index' (duration: 61.201575ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:28.659921Z","caller":"traceutil/trace.go:171","msg":"trace[1918901821] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"688.288714ms","start":"2026-05-06T00:52:27.971591Z","end":"2026-05-06T00:52:28.65988Z","steps":["trace[1918901821] 'process raft request' (duration: 687.852272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:28.660038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.637391721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-gbrfh\" ","response":"range_response_count:1 size:11057"} {"level":"warn","ts":"2026-05-06T00:52:28.660106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:27.971563Z","time spent":"688.44001ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:52:28.660116Z","caller":"traceutil/trace.go:171","msg":"trace[2039266195] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-gbrfh; range_end:; response_count:1; response_revision:1281; }","duration":"1.637525254s","start":"2026-05-06T00:52:27.022572Z","end":"2026-05-06T00:52:28.660097Z","steps":["trace[2039266195] 'agreement among raft nodes before linearized reading' (duration: 1.637313179s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:28.660155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:27.022549Z","time spent":"1.637598065s","remote":"127.0.0.1:54118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11079,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-gbrfh\" "} {"level":"warn","ts":"2026-05-06T00:52:28.660163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.648612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-06T00:52:28.660208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.820896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:52:28.660243Z","caller":"traceutil/trace.go:171","msg":"trace[1002539716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"605.894208ms","start":"2026-05-06T00:52:28.054338Z","end":"2026-05-06T00:52:28.660232Z","steps":["trace[1002539716] 'agreement among raft nodes before linearized reading' (duration: 605.750795ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:28.660244Z","caller":"traceutil/trace.go:171","msg":"trace[1134195574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1281; }","duration":"605.804095ms","start":"2026-05-06T00:52:28.05442Z","end":"2026-05-06T00:52:28.660224Z","steps":["trace[1134195574] 'agreement among raft nodes before linearized reading' (duration: 605.579379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:28.66028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:28.054316Z","time spent":"605.956159ms","remote":"127.0.0.1:46762","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-05-06T00:52:28.660294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:28.054394Z","time spent":"605.892028ms","remote":"127.0.0.1:46762","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-05-06T00:52:28.660064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.581409405s","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-05-06T00:52:28.66038Z","caller":"traceutil/trace.go:171","msg":"trace[2025497158] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1281; }","duration":"1.581802825s","start":"2026-05-06T00:52:27.078567Z","end":"2026-05-06T00:52:28.660369Z","steps":["trace[2025497158] 'agreement among raft nodes before linearized reading' (duration: 1.581420015s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:28.660412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:27.078544Z","time spent":"1.581854047s","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:52:28.660461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.077273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:52:28.660488Z","caller":"traceutil/trace.go:171","msg":"trace[181952641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1281; }","duration":"606.139656ms","start":"2026-05-06T00:52:28.054339Z","end":"2026-05-06T00:52:28.660479Z","steps":["trace[181952641] 'agreement among raft nodes before linearized reading' (duration: 606.056682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:28.66049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.700821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:52:28.660513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:28.054318Z","time spent":"606.189066ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:52:28.660566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.87304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:52:28.660599Z","caller":"traceutil/trace.go:171","msg":"trace[502589791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1281; }","duration":"596.91636ms","start":"2026-05-06T00:52:28.063673Z","end":"2026-05-06T00:52:28.660589Z","steps":["trace[502589791] 'agreement among raft nodes before linearized reading' (duration: 596.866009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:28.660625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:28.063664Z","time spent":"596.955411ms","remote":"127.0.0.1:53940","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-05-06T00:52:28.660522Z","caller":"traceutil/trace.go:171","msg":"trace[931341698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1281; }","duration":"704.763612ms","start":"2026-05-06T00:52:27.955749Z","end":"2026-05-06T00:52:28.660513Z","steps":["trace[931341698] 'agreement among raft nodes before linearized reading' (duration: 704.69343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:28.660675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:27.95573Z","time spent":"704.939286ms","remote":"127.0.0.1:53934","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-05-06T00:52:28.66078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.262212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-06T00:52:28.660807Z","caller":"traceutil/trace.go:171","msg":"trace[717654000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1281; }","duration":"605.307263ms","start":"2026-05-06T00:52:28.055493Z","end":"2026-05-06T00:52:28.6608Z","steps":["trace[717654000] 'agreement among raft nodes before linearized reading' (duration: 605.230891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:28.660828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:28.055485Z","time spent":"605.338924ms","remote":"127.0.0.1:46762","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-05-06T00:52:28.925324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.806045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:52:28.925481Z","caller":"traceutil/trace.go:171","msg":"trace[1040637908] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"260.958895ms","start":"2026-05-06T00:52:28.664496Z","end":"2026-05-06T00:52:28.925455Z","steps":["trace[1040637908] 'process raft request' (duration: 35.909663ms)","trace[1040637908] 'compare' (duration: 224.544928ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:28.932986Z","caller":"traceutil/trace.go:171","msg":"trace[1252600523] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"259.084095ms","start":"2026-05-06T00:52:28.673877Z","end":"2026-05-06T00:52:28.932961Z","steps":["trace[1252600523] 'process raft request' (duration: 259.011103ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:28.933084Z","caller":"traceutil/trace.go:171","msg":"trace[1890187846] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"259.425234ms","start":"2026-05-06T00:52:28.673634Z","end":"2026-05-06T00:52:28.93306Z","steps":["trace[1890187846] 'process raft request' (duration: 259.217009ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:28.93305Z","caller":"traceutil/trace.go:171","msg":"trace[1706926618] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"266.796299ms","start":"2026-05-06T00:52:28.666176Z","end":"2026-05-06T00:52:28.932972Z","steps":["trace[1706926618] 'process raft request' (duration: 266.46218ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:28.933347Z","caller":"traceutil/trace.go:171","msg":"trace[1884194465] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"264.562341ms","start":"2026-05-06T00:52:28.668755Z","end":"2026-05-06T00:52:28.933318Z","steps":["trace[1884194465] 'process raft request' (duration: 264.064138ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:40.548749Z","caller":"traceutil/trace.go:171","msg":"trace[642210790] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"548.487424ms","start":"2026-05-06T00:52:40.000229Z","end":"2026-05-06T00:52:40.548717Z","steps":["trace[642210790] 'process raft request' (duration: 548.334501ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:40.548827Z","caller":"traceutil/trace.go:171","msg":"trace[744855796] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1423; }","duration":"321.06513ms","start":"2026-05-06T00:52:40.227734Z","end":"2026-05-06T00:52:40.548799Z","steps":["trace[744855796] 'read index received' (duration: 321.05607ms)","trace[744855796] 'applied index is now lower than readState.Index' (duration: 6.91µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:52:40.548922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:40.000208Z","time spent":"548.629809ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:52:40.549018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.267956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-06T00:52:40.549099Z","caller":"traceutil/trace.go:171","msg":"trace[1736280484] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1367; }","duration":"321.581094ms","start":"2026-05-06T00:52:40.2275Z","end":"2026-05-06T00:52:40.549081Z","steps":["trace[1736280484] 'agreement among raft nodes before linearized reading' (duration: 321.386779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:40.549138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:40.227479Z","time spent":"321.651736ms","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-06T00:52:41.310028Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710900408167194523,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:52:41.808933Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.068297517s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T00:52:41.873863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203925517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T00:52:41.873975Z","caller":"traceutil/trace.go:171","msg":"trace[1199670316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1368; }","duration":"1.204097012s","start":"2026-05-06T00:52:40.669854Z","end":"2026-05-06T00:52:41.873951Z","steps":["trace[1199670316] 'range keys from in-memory index tree' (duration: 1.20366599s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:41.87405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:40.669831Z","time spent":"1.204185614s","remote":"127.0.0.1:46762","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-05-06T00:52:41.874867Z","caller":"traceutil/trace.go:171","msg":"trace[66450696] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"1.134313391s","start":"2026-05-06T00:52:40.740513Z","end":"2026-05-06T00:52:41.874826Z","steps":["trace[66450696] 'process raft request' (duration: 1.068659418s)","trace[66450696] 'compare' (duration: 65.343924ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:52:41.875156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:40.740484Z","time spent":"1.134571308s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:52:42.139246Z","caller":"traceutil/trace.go:171","msg":"trace[194382817] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1424; }","duration":"1.330060434s","start":"2026-05-06T00:52:40.809141Z","end":"2026-05-06T00:52:42.139202Z","steps":["trace[194382817] 'read index received' (duration: 1.000043368s)","trace[194382817] 'applied index is now lower than readState.Index' (duration: 330.015476ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:42.139377Z","caller":"traceutil/trace.go:171","msg":"trace[977103334] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"1.115645614s","start":"2026-05-06T00:52:41.023717Z","end":"2026-05-06T00:52:42.139362Z","steps":["trace[977103334] 'process raft request' (duration: 1.115311986s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:42.139494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:41.023685Z","time spent":"1.115721567s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:52:42.139525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330352382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:52:42.139632Z","caller":"traceutil/trace.go:171","msg":"trace[770197977] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1370; }","duration":"1.330476095s","start":"2026-05-06T00:52:40.809131Z","end":"2026-05-06T00:52:42.139607Z","steps":["trace[770197977] 'agreement among raft nodes before linearized reading' (duration: 1.330306611s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:42.139692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:40.809104Z","time spent":"1.330572118s","remote":"127.0.0.1:33140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:52:42.13992Z","caller":"traceutil/trace.go:171","msg":"trace[703730819] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"260.283277ms","start":"2026-05-06T00:52:41.879618Z","end":"2026-05-06T00:52:42.139901Z","steps":["trace[703730819] 'process raft request' (duration: 260.087812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:42.139965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.567198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:52:42.139997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.267603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.90\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-06T00:52:42.140026Z","caller":"traceutil/trace.go:171","msg":"trace[1270563140] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1371; }","duration":"149.667851ms","start":"2026-05-06T00:52:41.990341Z","end":"2026-05-06T00:52:42.140009Z","steps":["trace[1270563140] 'agreement among raft nodes before linearized reading' (duration: 149.574508ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:42.140047Z","caller":"traceutil/trace.go:171","msg":"trace[657349147] range","detail":"{range_begin:/registry/masterleases/199.19.213.90; range_end:; response_count:1; response_revision:1371; }","duration":"561.365085ms","start":"2026-05-06T00:52:41.578669Z","end":"2026-05-06T00:52:42.140035Z","steps":["trace[657349147] 'agreement among raft nodes before linearized reading' (duration: 561.206811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:42.140084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:41.578645Z","time spent":"561.431636ms","remote":"127.0.0.1:53954","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.90\" "} {"level":"warn","ts":"2026-05-06T00:52:42.140189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072049749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:52:42.140238Z","caller":"traceutil/trace.go:171","msg":"trace[2101035451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1371; }","duration":"1.0720985s","start":"2026-05-06T00:52:41.068131Z","end":"2026-05-06T00:52:42.14023Z","steps":["trace[2101035451] 'agreement among raft nodes before linearized reading' (duration: 1.072018178s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:42.140265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.927625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-06T00:52:42.140242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142266691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:52:42.140298Z","caller":"traceutil/trace.go:171","msg":"trace[771235596] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1371; }","duration":"463.000847ms","start":"2026-05-06T00:52:41.677289Z","end":"2026-05-06T00:52:42.140289Z","steps":["trace[771235596] 'agreement among raft nodes before linearized reading' (duration: 462.943195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:42.140277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:41.068042Z","time spent":"1.072224763s","remote":"127.0.0.1:53934","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-05-06T00:52:42.140328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:41.677269Z","time spent":"463.049668ms","remote":"127.0.0.1:46956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:52:42.140328Z","caller":"traceutil/trace.go:171","msg":"trace[1957415677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1371; }","duration":"1.142407805s","start":"2026-05-06T00:52:40.997901Z","end":"2026-05-06T00:52:42.140309Z","steps":["trace[1957415677] 'agreement among raft nodes before linearized reading' (duration: 1.142246161s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:42.140375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:40.997867Z","time spent":"1.142496528s","remote":"127.0.0.1:46762","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-05-06T00:52:42.140593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140780753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:52:42.140639Z","caller":"traceutil/trace.go:171","msg":"trace[1228990193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1371; }","duration":"1.141028618s","start":"2026-05-06T00:52:40.999601Z","end":"2026-05-06T00:52:42.140629Z","steps":["trace[1228990193] 'agreement among raft nodes before linearized reading' (duration: 1.14069131s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:42.140679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:40.999588Z","time spent":"1.14108014s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:52:42.140802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14942852s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-06T00:52:42.140827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150179871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:52:42.140861Z","caller":"traceutil/trace.go:171","msg":"trace[727839744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1371; }","duration":"1.150258543s","start":"2026-05-06T00:52:40.990595Z","end":"2026-05-06T00:52:42.140854Z","steps":["trace[727839744] 'agreement among raft nodes before linearized reading' (duration: 1.150155459s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:42.140866Z","caller":"traceutil/trace.go:171","msg":"trace[122378077] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1371; }","duration":"1.149503763s","start":"2026-05-06T00:52:40.991348Z","end":"2026-05-06T00:52:42.140852Z","steps":["trace[122378077] 'agreement among raft nodes before linearized reading' (duration: 1.149332797s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:42.140897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:40.990401Z","time spent":"1.15048973s","remote":"127.0.0.1:46762","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-05-06T00:52:42.140918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:40.991341Z","time spent":"1.149560875s","remote":"127.0.0.1:46762","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-05-06T00:52:42.141469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280122068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-06T00:52:42.14153Z","caller":"traceutil/trace.go:171","msg":"trace[588126523] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1371; }","duration":"1.280282664s","start":"2026-05-06T00:52:40.861237Z","end":"2026-05-06T00:52:42.14152Z","steps":["trace[588126523] 'agreement among raft nodes before linearized reading' (duration: 1.278585368s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:42.141616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:40.861221Z","time spent":"1.280382376s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-06T00:52:52.295519Z","caller":"traceutil/trace.go:171","msg":"trace[1305554153] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"115.403622ms","start":"2026-05-06T00:52:52.180079Z","end":"2026-05-06T00:52:52.295483Z","steps":["trace[1305554153] 'process raft request' (duration: 115.122235ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:53:32.910904Z","caller":"traceutil/trace.go:171","msg":"trace[1246208330] linearizableReadLoop","detail":"{readStateIndex:1674; appliedIndex:1672; }","duration":"100.213164ms","start":"2026-05-06T00:53:32.810663Z","end":"2026-05-06T00:53:32.910876Z","steps":["trace[1246208330] 'read index received' (duration: 30.47064ms)","trace[1246208330] 'applied index is now lower than readState.Index' (duration: 69.741504ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:53:32.911109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.417421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:53:32.911213Z","caller":"traceutil/trace.go:171","msg":"trace[923748430] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1607; }","duration":"100.576735ms","start":"2026-05-06T00:53:32.810616Z","end":"2026-05-06T00:53:32.911193Z","steps":["trace[923748430] 'agreement among raft nodes before linearized reading' (duration: 100.37422ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:53:32.911022Z","caller":"traceutil/trace.go:171","msg":"trace[1640365884] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"126.219547ms","start":"2026-05-06T00:53:32.784753Z","end":"2026-05-06T00:53:32.910973Z","steps":["trace[1640365884] 'process raft request' (duration: 123.264387ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:54:09.91034Z","caller":"traceutil/trace.go:171","msg":"trace[641290259] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"135.398941ms","start":"2026-05-06T00:54:09.774908Z","end":"2026-05-06T00:54:09.910307Z","steps":["trace[641290259] 'process raft request' (duration: 135.244076ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:54:11.217406Z","caller":"traceutil/trace.go:171","msg":"trace[939166236] linearizableReadLoop","detail":"{readStateIndex:1855; appliedIndex:1854; }","duration":"113.729675ms","start":"2026-05-06T00:54:11.103655Z","end":"2026-05-06T00:54:11.217385Z","steps":["trace[939166236] 'read index received' (duration: 113.548559ms)","trace[939166236] 'applied index is now lower than readState.Index' (duration: 180.316µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:54:11.217532Z","caller":"traceutil/trace.go:171","msg":"trace[327096409] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"114.212837ms","start":"2026-05-06T00:54:11.103261Z","end":"2026-05-06T00:54:11.217473Z","steps":["trace[327096409] 'process raft request' (duration: 114.013981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:54:11.21764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.970011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-06T00:54:11.21764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.421136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-06T00:54:11.217671Z","caller":"traceutil/trace.go:171","msg":"trace[1356600122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1781; }","duration":"114.046723ms","start":"2026-05-06T00:54:11.103616Z","end":"2026-05-06T00:54:11.217663Z","steps":["trace[1356600122] 'agreement among raft nodes before linearized reading' (duration: 113.9282ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:54:11.217694Z","caller":"traceutil/trace.go:171","msg":"trace[1698981046] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1781; }","duration":"104.483358ms","start":"2026-05-06T00:54:11.113183Z","end":"2026-05-06T00:54:11.217666Z","steps":["trace[1698981046] 'agreement among raft nodes before linearized reading' (duration: 104.401756ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:55:53.80534Z","caller":"traceutil/trace.go:171","msg":"trace[491864704] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"197.940915ms","start":"2026-05-06T00:55:53.607362Z","end":"2026-05-06T00:55:53.805303Z","steps":["trace[491864704] 'process raft request' (duration: 197.359719ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:56:18.086213Z","caller":"traceutil/trace.go:171","msg":"trace[249113006] linearizableReadLoop","detail":"{readStateIndex:2499; appliedIndex:2498; }","duration":"102.354935ms","start":"2026-05-06T00:56:17.983835Z","end":"2026-05-06T00:56:18.08619Z","steps":["trace[249113006] 'read index received' (duration: 101.163584ms)","trace[249113006] 'applied index is now lower than readState.Index' (duration: 1.19021ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:56:18.086526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.674303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-06T00:56:18.086542Z","caller":"traceutil/trace.go:171","msg":"trace[1620120691] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"103.226237ms","start":"2026-05-06T00:56:17.983144Z","end":"2026-05-06T00:56:18.08637Z","steps":["trace[1620120691] 'process raft request' (duration: 101.79478ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:56:18.086588Z","caller":"traceutil/trace.go:171","msg":"trace[1593735463] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2398; }","duration":"102.767646ms","start":"2026-05-06T00:56:17.983807Z","end":"2026-05-06T00:56:18.086574Z","steps":["trace[1593735463] 'agreement among raft nodes before linearized reading' (duration: 102.598212ms)"],"step_count":1}