{"level":"warn","ts":"2026-04-09T22:29:39.542096Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T22:29:39.542193Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-04-09T22:29:39.542269Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T22:29:39.542278Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-09T22:29:39.542304Z","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-04-09T22:29:39.543206Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-04-09T22:29:39.543293Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-04-09T22:29:39.547367Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.826883ms"} {"level":"info","ts":"2026-04-09T22:29:39.555714Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-04-09T22:29:39.555847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T22:29:39.555901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-04-09T22:29:39.555941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T22:29:39.555965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-04-09T22:29:39.556034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-04-09T22:29:39.567249Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T22:29:39.569537Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T22:29:39.57117Z","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-04-09T22:29:39.573843Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T22:29:39.574034Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T22:29:39.574769Z","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-04-09T22:29:39.575956Z","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-04-09T22:29:39.575992Z","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-04-09T22:29:39.577454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-04-09T22:29:39.577697Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-09T22:29:39.579069Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-09T22:29:39.579122Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-09T22:29:39.579031Z","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-04-09T22:29:39.580364Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T22:29:39.580316Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T22:29:40.057146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T22:29:40.057209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T22:29:40.057235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-04-09T22:29:40.057275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-04-09T22:29:40.057296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-09T22:29:40.057319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-04-09T22:29:40.057362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-09T22:29:40.109937Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T22:29:40.109998Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:29:40.11011Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:29:40.110604Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T22:29:40.110738Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T22:29:40.109974Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:29:40.116075Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:29:40.116144Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T22:29:40.116374Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:29:40.116501Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:29:40.118191Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"warn","ts":"2026-04-09T22:29:42.098353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.184207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-09T22:29:42.098453Z","caller":"traceutil/trace.go:171","msg":"trace[1644349706] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"337.721967ms","start":"2026-04-09T22:29:41.760717Z","end":"2026-04-09T22:29:42.098439Z","steps":["trace[1644349706] 'process raft request' (duration: 63.096139ms)","trace[1644349706] 'compare' (duration: 274.065295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:42.098679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:41.760681Z","time spent":"337.803029ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:29:42.111368Z","caller":"traceutil/trace.go:171","msg":"trace[721535656] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"350.454887ms","start":"2026-04-09T22:29:41.760896Z","end":"2026-04-09T22:29:42.111351Z","steps":["trace[721535656] 'process raft request' (duration: 350.267443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:42.111513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:41.760863Z","time spent":"350.556589ms","remote":"127.0.0.1:58118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T22:29:42.111568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.152105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T22:29:42.111339Z","caller":"traceutil/trace.go:171","msg":"trace[250734869] linearizableReadLoop","detail":"{readStateIndex:74; appliedIndex:73; }","duration":"248.90602ms","start":"2026-04-09T22:29:41.862396Z","end":"2026-04-09T22:29:42.111302Z","steps":["trace[250734869] 'read index received' (duration: 248.752276ms)","trace[250734869] 'applied index is now lower than readState.Index' (duration: 151.654µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:29:42.11171Z","caller":"traceutil/trace.go:171","msg":"trace[570661101] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:70; }","duration":"249.383302ms","start":"2026-04-09T22:29:41.862306Z","end":"2026-04-09T22:29:42.11169Z","steps":["trace[570661101] 'agreement among raft nodes before linearized reading' (duration: 249.102245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:43.657899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.147447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:29:43.658132Z","caller":"traceutil/trace.go:171","msg":"trace[62914336] transaction","detail":"{read_only:false; response_revision:186; number_of_response:1; }","duration":"413.734861ms","start":"2026-04-09T22:29:43.244365Z","end":"2026-04-09T22:29:43.6581Z","steps":["trace[62914336] 'process raft request' (duration: 312.268935ms)","trace[62914336] 'compare' (duration: 100.936092ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:43.65821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:43.244347Z","time spent":"413.836343ms","remote":"127.0.0.1:58278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":841,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:29:48.017041Z","caller":"traceutil/trace.go:171","msg":"trace[76543074] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:292; }","duration":"323.467249ms","start":"2026-04-09T22:29:47.693546Z","end":"2026-04-09T22:29:48.017014Z","steps":["trace[76543074] 'read index received' (duration: 323.252103ms)","trace[76543074] 'applied index is now lower than readState.Index' (duration: 213.826µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:29:48.017161Z","caller":"traceutil/trace.go:171","msg":"trace[1139649570] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"352.377064ms","start":"2026-04-09T22:29:47.664747Z","end":"2026-04-09T22:29:48.017124Z","steps":["trace[1139649570] 'process raft request' (duration: 352.108696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:48.017263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.709835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-09T22:29:48.017322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:47.664518Z","time spent":"352.741833ms","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:29:48.017338Z","caller":"traceutil/trace.go:171","msg":"trace[1232125708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:281; }","duration":"323.841388ms","start":"2026-04-09T22:29:47.693479Z","end":"2026-04-09T22:29:48.01732Z","steps":["trace[1232125708] 'agreement among raft nodes before linearized reading' (duration: 323.661604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:48.017402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:47.693452Z","time spent":"323.914759ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T22:29:48.019405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.612058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:29:48.022616Z","caller":"traceutil/trace.go:171","msg":"trace[2055102587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:281; }","duration":"323.855057ms","start":"2026-04-09T22:29:47.69874Z","end":"2026-04-09T22:29:48.022595Z","steps":["trace[2055102587] 'agreement among raft nodes before linearized reading' (duration: 318.448266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:48.022692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:47.698725Z","time spent":"323.95028ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T22:29:48.524858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959726471112359,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T22:29:49.050462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.566135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:29:49.050672Z","caller":"traceutil/trace.go:171","msg":"trace[2031153608] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:293; }","duration":"1.02679818s","start":"2026-04-09T22:29:48.023856Z","end":"2026-04-09T22:29:49.050655Z","steps":["trace[2031153608] 'read index received' (duration: 571.964589ms)","trace[2031153608] 'applied index is now lower than readState.Index' (duration: 454.832201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:49.050762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026904024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-09T22:29:49.05079Z","caller":"traceutil/trace.go:171","msg":"trace[529644482] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:284; }","duration":"1.026963695s","start":"2026-04-09T22:29:48.023816Z","end":"2026-04-09T22:29:49.05078Z","steps":["trace[529644482] 'agreement among raft nodes before linearized reading' (duration: 1.026886212s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:49.050824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:48.023804Z","time spent":"1.027011966s","remote":"127.0.0.1:58042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-09T22:29:49.050868Z","caller":"traceutil/trace.go:171","msg":"trace[628987762] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"1.028570494s","start":"2026-04-09T22:29:48.022248Z","end":"2026-04-09T22:29:49.050819Z","steps":["trace[628987762] 'process raft request' (duration: 573.386933ms)","trace[628987762] 'compare' (duration: 454.438302ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:29:49.050962Z","caller":"traceutil/trace.go:171","msg":"trace[1316342592] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"1.028430291s","start":"2026-04-09T22:29:48.022502Z","end":"2026-04-09T22:29:49.050933Z","steps":["trace[1316342592] 'process raft request' (duration: 1.028066661s)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:49.051109Z","caller":"traceutil/trace.go:171","msg":"trace[689324538] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"1.025473958s","start":"2026-04-09T22:29:48.025624Z","end":"2026-04-09T22:29:49.051098Z","steps":["trace[689324538] 'process raft request' (duration: 1.024998316s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:49.051184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:48.025606Z","time spent":"1.02553386s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:29:49.051191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:48.022224Z","time spent":"1.028734649s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:29:49.051117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:48.022481Z","time spent":"1.028546934s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:29:49.733932Z","caller":"traceutil/trace.go:171","msg":"trace[1920722518] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:297; }","duration":"255.664195ms","start":"2026-04-09T22:29:49.478221Z","end":"2026-04-09T22:29:49.733885Z","steps":["trace[1920722518] 'read index received' (duration: 212.008641ms)","trace[1920722518] 'applied index is now lower than readState.Index' (duration: 43.654014ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:29:49.734054Z","caller":"traceutil/trace.go:171","msg":"trace[549969344] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"650.112504ms","start":"2026-04-09T22:29:49.083867Z","end":"2026-04-09T22:29:49.73398Z","steps":["trace[549969344] 'process raft request' (duration: 606.404649ms)","trace[549969344] 'compare' (duration: 43.420849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:49.734193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.940611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-09T22:29:49.734216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:49.083854Z","time spent":"650.289038ms","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:29:49.73424Z","caller":"traceutil/trace.go:171","msg":"trace[1944682431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:286; }","duration":"256.078404ms","start":"2026-04-09T22:29:49.478147Z","end":"2026-04-09T22:29:49.734226Z","steps":["trace[1944682431] 'agreement among raft nodes before linearized reading' (duration: 255.90598ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:04.041634Z","caller":"traceutil/trace.go:171","msg":"trace[1018363833] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"108.366673ms","start":"2026-04-09T22:30:03.933225Z","end":"2026-04-09T22:30:04.041592Z","steps":["trace[1018363833] 'process raft request' (duration: 108.103617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.042939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.903559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:30:13.043102Z","caller":"traceutil/trace.go:171","msg":"trace[1537134857] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"532.066366ms","start":"2026-04-09T22:30:12.511012Z","end":"2026-04-09T22:30:13.043078Z","steps":["trace[1537134857] 'process raft request' (duration: 166.944172ms)","trace[1537134857] 'compare' (duration: 364.616031ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:13.043159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.510984Z","time spent":"532.152218ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:30:13.444636Z","caller":"traceutil/trace.go:171","msg":"trace[772645276] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"890.981278ms","start":"2026-04-09T22:30:12.553621Z","end":"2026-04-09T22:30:13.444602Z","steps":["trace[772645276] 'process raft request' (duration: 890.866625ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:13.444712Z","caller":"traceutil/trace.go:171","msg":"trace[482708265] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"897.234691ms","start":"2026-04-09T22:30:12.54744Z","end":"2026-04-09T22:30:13.444675Z","steps":["trace[482708265] 'process raft request' (duration: 897.004395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.444843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.55357Z","time spent":"891.176853ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:30:13.444872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.547414Z","time spent":"897.380635ms","remote":"127.0.0.1:58486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:30:13.445211Z","caller":"traceutil/trace.go:171","msg":"trace[1760032744] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"881.342223ms","start":"2026-04-09T22:30:12.563856Z","end":"2026-04-09T22:30:13.445198Z","steps":["trace[1760032744] 'process raft request' (duration: 880.933223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.445273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.563849Z","time spent":"881.391345ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:30:13.44531Z","caller":"traceutil/trace.go:171","msg":"trace[1830743012] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"869.2563ms","start":"2026-04-09T22:30:12.576041Z","end":"2026-04-09T22:30:13.445298Z","steps":["trace[1830743012] 'process raft request' (duration: 869.063995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.445368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.576021Z","time spent":"869.319921ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:30:13.445459Z","caller":"traceutil/trace.go:171","msg":"trace[1024468435] transaction","detail":"{read_only:false; number_of_response:1; response_revision:478; }","duration":"881.57757ms","start":"2026-04-09T22:30:12.563872Z","end":"2026-04-09T22:30:13.44545Z","steps":["trace[1024468435] 'process raft request' (duration: 881.163489ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:13.445496Z","caller":"traceutil/trace.go:171","msg":"trace[1835054034] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"881.729423ms","start":"2026-04-09T22:30:12.563756Z","end":"2026-04-09T22:30:13.445485Z","steps":["trace[1835054034] 'process raft request' (duration: 880.79689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.445507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.563825Z","time spent":"881.653431ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:30:13.445545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.563732Z","time spent":"881.789304ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:30:13.445556Z","caller":"traceutil/trace.go:171","msg":"trace[2035801036] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"830.971835ms","start":"2026-04-09T22:30:12.614554Z","end":"2026-04-09T22:30:13.445525Z","steps":["trace[2035801036] 'process raft request' (duration: 830.909804ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:13.445651Z","caller":"traceutil/trace.go:171","msg":"trace[1618908652] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"858.994389ms","start":"2026-04-09T22:30:12.586648Z","end":"2026-04-09T22:30:13.445642Z","steps":["trace[1618908652] 'process raft request' (duration: 858.558709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.445738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.586623Z","time spent":"859.095711ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:30:13.445804Z","caller":"traceutil/trace.go:171","msg":"trace[451654727] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"867.347543ms","start":"2026-04-09T22:30:12.57845Z","end":"2026-04-09T22:30:13.445797Z","steps":["trace[451654727] 'process raft request' (duration: 866.713968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.445804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.614519Z","time spent":"831.188871ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T22:30:13.445838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.578441Z","time spent":"867.381124ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:30:13.445953Z","caller":"traceutil/trace.go:171","msg":"trace[1410959048] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"855.975435ms","start":"2026-04-09T22:30:12.589966Z","end":"2026-04-09T22:30:13.445941Z","steps":["trace[1410959048] 'process raft request' (duration: 855.34216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.446003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.589939Z","time spent":"856.039816ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:30:13.446096Z","caller":"traceutil/trace.go:171","msg":"trace[480020446] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"837.919934ms","start":"2026-04-09T22:30:12.608168Z","end":"2026-04-09T22:30:13.446088Z","steps":["trace[480020446] 'process raft request' (duration: 837.206177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.446145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.608142Z","time spent":"837.973916ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:30:13.446261Z","caller":"traceutil/trace.go:171","msg":"trace[1025057861] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"903.113193ms","start":"2026-04-09T22:30:12.54142Z","end":"2026-04-09T22:30:13.444533Z","steps":["trace[1025057861] 'process raft request' (duration: 902.864837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.446541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.541395Z","time spent":"904.98686ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:30:13.454836Z","caller":"traceutil/trace.go:171","msg":"trace[667604006] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"731.293534ms","start":"2026-04-09T22:30:12.723528Z","end":"2026-04-09T22:30:13.454821Z","steps":["trace[667604006] 'process raft request' (duration: 731.049428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.455309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.723488Z","time spent":"731.714684ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:30:13.455557Z","caller":"traceutil/trace.go:171","msg":"trace[1337336574] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"581.940842ms","start":"2026-04-09T22:30:12.8736Z","end":"2026-04-09T22:30:13.455541Z","steps":["trace[1337336574] 'process raft request' (duration: 581.106102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.455634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:12.87357Z","time spent":"582.032104ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:13.455823Z","caller":"traceutil/trace.go:171","msg":"trace[1247309263] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"407.417515ms","start":"2026-04-09T22:30:13.048373Z","end":"2026-04-09T22:30:13.455791Z","steps":["trace[1247309263] 'process raft request' (duration: 406.37626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:13.455929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:13.048346Z","time spent":"407.535558ms","remote":"127.0.0.1:58486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:30:14.273672Z","caller":"traceutil/trace.go:171","msg":"trace[1618368427] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"218.139108ms","start":"2026-04-09T22:30:14.055484Z","end":"2026-04-09T22:30:14.273623Z","steps":["trace[1618368427] 'process raft request' (duration: 207.632247ms)","trace[1618368427] 'compare' (duration: 10.212464ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:14.275479Z","caller":"traceutil/trace.go:171","msg":"trace[1843530978] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"209.304177ms","start":"2026-04-09T22:30:14.066156Z","end":"2026-04-09T22:30:14.27546Z","steps":["trace[1843530978] 'process raft request' (duration: 208.593871ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:14.275862Z","caller":"traceutil/trace.go:171","msg":"trace[1330063519] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:561; }","duration":"160.213182ms","start":"2026-04-09T22:30:14.115617Z","end":"2026-04-09T22:30:14.27583Z","steps":["trace[1330063519] 'read index received' (duration: 147.470337ms)","trace[1330063519] 'applied index is now lower than readState.Index' (duration: 12.741885ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:14.275891Z","caller":"traceutil/trace.go:171","msg":"trace[861726121] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"129.249962ms","start":"2026-04-09T22:30:14.146602Z","end":"2026-04-09T22:30:14.275852Z","steps":["trace[861726121] 'process raft request' (duration: 128.77822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:14.2761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.460088ms","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-04-09T22:30:14.276138Z","caller":"traceutil/trace.go:171","msg":"trace[319088150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"160.56182ms","start":"2026-04-09T22:30:14.115565Z","end":"2026-04-09T22:30:14.276127Z","steps":["trace[319088150] 'agreement among raft nodes before linearized reading' (duration: 160.378256ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:17.276472Z","caller":"traceutil/trace.go:171","msg":"trace[111350108] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"206.13724ms","start":"2026-04-09T22:30:17.0703Z","end":"2026-04-09T22:30:17.276438Z","steps":["trace[111350108] 'process raft request' (duration: 205.602548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:17.832205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.09883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:30:17.832446Z","caller":"traceutil/trace.go:171","msg":"trace[212252273] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"384.102428ms","start":"2026-04-09T22:30:17.448323Z","end":"2026-04-09T22:30:17.832425Z","steps":["trace[212252273] 'read index received' (duration: 139.622109ms)","trace[212252273] 'applied index is now lower than readState.Index' (duration: 244.478939ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:17.832542Z","caller":"traceutil/trace.go:171","msg":"trace[256601817] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"499.084618ms","start":"2026-04-09T22:30:17.333415Z","end":"2026-04-09T22:30:17.8325Z","steps":["trace[256601817] 'process raft request' (duration: 254.600379ms)","trace[256601817] 'compare' (duration: 243.914794ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:17.832743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:17.333382Z","time spent":"499.252673ms","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T22:30:17.832787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.413375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:17.832838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.713441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T22:30:17.83297Z","caller":"traceutil/trace.go:171","msg":"trace[399352876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"384.666501ms","start":"2026-04-09T22:30:17.448269Z","end":"2026-04-09T22:30:17.832935Z","steps":["trace[399352876] 'agreement among raft nodes before linearized reading' (duration: 384.265821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:17.83314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:17.44825Z","time spent":"384.866767ms","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T22:30:17.833204Z","caller":"traceutil/trace.go:171","msg":"trace[304880214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"353.860054ms","start":"2026-04-09T22:30:17.479059Z","end":"2026-04-09T22:30:17.832919Z","steps":["trace[304880214] 'agreement among raft nodes before linearized reading' (duration: 353.645899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:17.833272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:17.479028Z","time spent":"354.230493ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T22:30:17.833204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.737491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:30:17.833378Z","caller":"traceutil/trace.go:171","msg":"trace[1105058665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:563; }","duration":"353.935206ms","start":"2026-04-09T22:30:17.479429Z","end":"2026-04-09T22:30:17.833364Z","steps":["trace[1105058665] 'agreement among raft nodes before linearized reading' (duration: 353.165218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:17.833474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:17.479421Z","time spent":"354.009427ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T22:30:17.833935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.725097ms","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-04-09T22:30:17.833992Z","caller":"traceutil/trace.go:171","msg":"trace[99615279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"357.840329ms","start":"2026-04-09T22:30:17.476138Z","end":"2026-04-09T22:30:17.833978Z","steps":["trace[99615279] 'agreement among raft nodes before linearized reading' (duration: 356.460867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:17.834027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:17.476112Z","time spent":"357.907551ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T22:30:18.226556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.349966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:30:18.226811Z","caller":"traceutil/trace.go:171","msg":"trace[888389774] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"386.947845ms","start":"2026-04-09T22:30:17.839842Z","end":"2026-04-09T22:30:18.22679Z","steps":["trace[888389774] 'process raft request' (duration: 386.852443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:18.226886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:17.839835Z","time spent":"387.015327ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:18.22687Z","caller":"traceutil/trace.go:171","msg":"trace[25554527] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"388.166074ms","start":"2026-04-09T22:30:17.838641Z","end":"2026-04-09T22:30:18.226807Z","steps":["trace[25554527] 'process raft request' (duration: 102.458871ms)","trace[25554527] 'compare' (duration: 285.212892ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:18.226906Z","caller":"traceutil/trace.go:171","msg":"trace[1291246428] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:586; }","duration":"383.154714ms","start":"2026-04-09T22:30:17.843719Z","end":"2026-04-09T22:30:18.226873Z","steps":["trace[1291246428] 'read index received' (duration: 97.400219ms)","trace[1291246428] 'applied index is now lower than readState.Index' (duration: 285.753235ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:18.227131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:17.838624Z","time spent":"388.350468ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:30:18.227137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.38111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:18.227363Z","caller":"traceutil/trace.go:171","msg":"trace[458450711] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:566; }","duration":"383.657667ms","start":"2026-04-09T22:30:17.84368Z","end":"2026-04-09T22:30:18.227338Z","steps":["trace[458450711] 'agreement among raft nodes before linearized reading' (duration: 383.315058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:18.227425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:17.843664Z","time spent":"383.744279ms","remote":"127.0.0.1:58266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-09T22:30:18.227279Z","caller":"traceutil/trace.go:171","msg":"trace[925972811] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"387.358506ms","start":"2026-04-09T22:30:17.839907Z","end":"2026-04-09T22:30:18.227266Z","steps":["trace[925972811] 'process raft request' (duration: 386.845353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:18.228043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:17.839879Z","time spent":"387.663524ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:30:18.6569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.474943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:30:18.657057Z","caller":"traceutil/trace.go:171","msg":"trace[1450243626] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"207.981054ms","start":"2026-04-09T22:30:18.449024Z","end":"2026-04-09T22:30:18.657005Z","steps":["trace[1450243626] 'read index received' (duration: 97.120422ms)","trace[1450243626] 'applied index is now lower than readState.Index' (duration: 110.859362ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:18.657124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.105588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:18.657152Z","caller":"traceutil/trace.go:171","msg":"trace[1095182244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"208.171038ms","start":"2026-04-09T22:30:18.44897Z","end":"2026-04-09T22:30:18.657141Z","steps":["trace[1095182244] 'agreement among raft nodes before linearized reading' (duration: 208.119077ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:18.657213Z","caller":"traceutil/trace.go:171","msg":"trace[621255659] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"356.971778ms","start":"2026-04-09T22:30:18.300208Z","end":"2026-04-09T22:30:18.65718Z","steps":["trace[621255659] 'process raft request' (duration: 246.018764ms)","trace[621255659] 'compare' (duration: 110.33555ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:18.657317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:18.300183Z","time spent":"357.084021ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:19.375582Z","caller":"traceutil/trace.go:171","msg":"trace[587718476] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"132.465348ms","start":"2026-04-09T22:30:19.24308Z","end":"2026-04-09T22:30:19.375545Z","steps":["trace[587718476] 'process raft request' (duration: 131.538796ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:20.337425Z","caller":"traceutil/trace.go:171","msg":"trace[1590457614] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"103.061026ms","start":"2026-04-09T22:30:20.234335Z","end":"2026-04-09T22:30:20.337396Z","steps":["trace[1590457614] 'process raft request' (duration: 102.933063ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:20.337863Z","caller":"traceutil/trace.go:171","msg":"trace[685311828] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"102.252226ms","start":"2026-04-09T22:30:20.235597Z","end":"2026-04-09T22:30:20.337849Z","steps":["trace[685311828] 'process raft request' (duration: 101.753175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:20.601799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.497752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:30:20.602136Z","caller":"traceutil/trace.go:171","msg":"trace[1366364140] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"217.909242ms","start":"2026-04-09T22:30:20.384186Z","end":"2026-04-09T22:30:20.602095Z","steps":["trace[1366364140] 'process raft request' (duration: 23.01064ms)","trace[1366364140] 'compare' (duration: 194.119743ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:22.220602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.029032ms","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:5"} {"level":"warn","ts":"2026-04-09T22:30:22.22067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.084398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:22.220751Z","caller":"traceutil/trace.go:171","msg":"trace[1115461228] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:584; }","duration":"623.227427ms","start":"2026-04-09T22:30:21.597481Z","end":"2026-04-09T22:30:22.220708Z","steps":["trace[1115461228] 'count revisions from in-memory index tree' (duration: 622.975511ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:22.220764Z","caller":"traceutil/trace.go:171","msg":"trace[709465048] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:584; }","duration":"665.250062ms","start":"2026-04-09T22:30:21.555493Z","end":"2026-04-09T22:30:22.220743Z","steps":["trace[709465048] 'count revisions from in-memory index tree' (duration: 664.899654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:22.220814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.555455Z","time spent":"665.347934ms","remote":"127.0.0.1:51748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:30:22.220827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.597476Z","time spent":"623.330049ms","remote":"127.0.0.1:51766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:30:22.221015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.938664ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:22.221055Z","caller":"traceutil/trace.go:171","msg":"trace[1209625249] 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:584; }","duration":"606.016886ms","start":"2026-04-09T22:30:21.615027Z","end":"2026-04-09T22:30:22.221043Z","steps":["trace[1209625249] 'range keys from in-memory index tree' (duration: 605.848691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:22.22099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.771012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wbxv7\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-09T22:30:22.22111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.763049ms","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":"warn","ts":"2026-04-09T22:30:22.221104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.914656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:22.221128Z","caller":"traceutil/trace.go:171","msg":"trace[909686202] 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:584; }","duration":"605.840321ms","start":"2026-04-09T22:30:21.615282Z","end":"2026-04-09T22:30:22.221122Z","steps":["trace[909686202] 'count revisions from in-memory index tree' (duration: 605.650387ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:22.221138Z","caller":"traceutil/trace.go:171","msg":"trace[1886055958] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:584; }","duration":"695.991337ms","start":"2026-04-09T22:30:21.525136Z","end":"2026-04-09T22:30:22.221127Z","steps":["trace[1886055958] 'range keys from in-memory index tree' (duration: 695.786223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:22.221149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.615253Z","time spent":"605.890042ms","remote":"127.0.0.1:51776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:30:22.221168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.525104Z","time spent":"696.055249ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-09T22:30:22.221165Z","caller":"traceutil/trace.go:171","msg":"trace[1586055114] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wbxv7; range_end:; response_count:1; response_revision:584; }","duration":"580.959916ms","start":"2026-04-09T22:30:21.64017Z","end":"2026-04-09T22:30:22.22113Z","steps":["trace[1586055114] 'range keys from in-memory index tree' (duration: 580.535956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:22.221252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.640144Z","time spent":"581.093189ms","remote":"127.0.0.1:58118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-wbxv7\" "} {"level":"warn","ts":"2026-04-09T22:30:22.221314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.59147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:22.221356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.356715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:22.22137Z","caller":"traceutil/trace.go:171","msg":"trace[1431732482] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:584; }","duration":"665.658102ms","start":"2026-04-09T22:30:21.555696Z","end":"2026-04-09T22:30:22.221354Z","steps":["trace[1431732482] 'range keys from in-memory index tree' (duration: 665.539228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:22.22141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.595632ms","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:5"} {"level":"warn","ts":"2026-04-09T22:30:22.221411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.555691Z","time spent":"665.710723ms","remote":"127.0.0.1:51748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"info","ts":"2026-04-09T22:30:22.22143Z","caller":"traceutil/trace.go:171","msg":"trace[851484088] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:584; }","duration":"645.616282ms","start":"2026-04-09T22:30:21.575807Z","end":"2026-04-09T22:30:22.221423Z","steps":["trace[851484088] 'count revisions from in-memory index tree' (duration: 645.50805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:22.221451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.57576Z","time spent":"645.684584ms","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:30:22.221508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.055551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:22.220799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.519063ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:22.22157Z","caller":"traceutil/trace.go:171","msg":"trace[728798165] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:584; }","duration":"666.142083ms","start":"2026-04-09T22:30:21.555408Z","end":"2026-04-09T22:30:22.22155Z","steps":["trace[728798165] 'range keys from in-memory index tree' (duration: 665.9982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:22.221586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.20277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:22.221066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.917356ms","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":"info","ts":"2026-04-09T22:30:22.221584Z","caller":"traceutil/trace.go:171","msg":"trace[988594288] 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:584; }","duration":"606.362784ms","start":"2026-04-09T22:30:21.615203Z","end":"2026-04-09T22:30:22.221566Z","steps":["trace[988594288] 'range keys from in-memory index tree' (duration: 605.466312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:22.221803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.529417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:22.221078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.615007Z","time spent":"606.063066ms","remote":"127.0.0.1:51776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-04-09T22:30:22.221856Z","caller":"traceutil/trace.go:171","msg":"trace[217819391] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:584; }","duration":"686.103141ms","start":"2026-04-09T22:30:21.535743Z","end":"2026-04-09T22:30:22.221846Z","steps":["trace[217819391] 'range keys from in-memory index tree' (duration: 685.430065ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:22.221382Z","caller":"traceutil/trace.go:171","msg":"trace[2047647494] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:584; }","duration":"624.414266ms","start":"2026-04-09T22:30:21.596957Z","end":"2026-04-09T22:30:22.221371Z","steps":["trace[2047647494] 'range keys from in-memory index tree' (duration: 624.284223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:22.221747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.615197Z","time spent":"606.531898ms","remote":"127.0.0.1:51776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-09T22:30:22.221896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.535719Z","time spent":"686.167433ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-09T22:30:22.221789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.868478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:22.221919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.596938Z","time spent":"624.97034ms","remote":"127.0.0.1:51766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-04-09T22:30:22.222086Z","caller":"traceutil/trace.go:171","msg":"trace[384668150] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:584; }","duration":"646.31518ms","start":"2026-04-09T22:30:21.575727Z","end":"2026-04-09T22:30:22.222042Z","steps":["trace[384668150] 'range keys from in-memory index tree' (duration: 645.639933ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:22.221621Z","caller":"traceutil/trace.go:171","msg":"trace[1192362461] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:584; }","duration":"624.248081ms","start":"2026-04-09T22:30:21.597363Z","end":"2026-04-09T22:30:22.221611Z","steps":["trace[1192362461] 'range keys from in-memory index tree' (duration: 624.157909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:22.222258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.597358Z","time spent":"624.884637ms","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-04-09T22:30:22.22225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.575702Z","time spent":"646.520774ms","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-09T22:30:22.221607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.555398Z","time spent":"666.199755ms","remote":"127.0.0.1:51748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"info","ts":"2026-04-09T22:30:22.221681Z","caller":"traceutil/trace.go:171","msg":"trace[873749414] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:584; }","duration":"696.562601ms","start":"2026-04-09T22:30:21.525094Z","end":"2026-04-09T22:30:22.221657Z","steps":["trace[873749414] 'count revisions from in-memory index tree' (duration: 695.759882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:22.222763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.52506Z","time spent":"697.679118ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:30:22.829405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959726471112995,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T22:30:22.887016Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.244505968s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T22:30:22.888675Z","caller":"traceutil/trace.go:171","msg":"trace[2057997147] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.248195817s","start":"2026-04-09T22:30:21.640451Z","end":"2026-04-09T22:30:22.888647Z","steps":["trace[2057997147] 'process raft request' (duration: 1.24710753s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:22.888861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:21.640416Z","time spent":"1.248338241s","remote":"127.0.0.1:58018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:23.510499Z","caller":"traceutil/trace.go:171","msg":"trace[1111583859] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.273584003s","start":"2026-04-09T22:30:22.236842Z","end":"2026-04-09T22:30:23.510426Z","steps":["trace[1111583859] 'process raft request' (duration: 1.273349958s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:23.510698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:22.23682Z","time spent":"1.273775199s","remote":"127.0.0.1:58118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:30:23.51067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181655175s","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-04-09T22:30:23.5108Z","caller":"traceutil/trace.go:171","msg":"trace[1935156924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"1.181833109s","start":"2026-04-09T22:30:22.328937Z","end":"2026-04-09T22:30:23.51077Z","steps":["trace[1935156924] 'agreement among raft nodes before linearized reading' (duration: 1.181622764s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:23.510869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:22.328915Z","time spent":"1.181937262s","remote":"127.0.0.1:58216","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-04-09T22:30:23.510911Z","caller":"traceutil/trace.go:171","msg":"trace[1276554729] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"1.181385278s","start":"2026-04-09T22:30:22.328983Z","end":"2026-04-09T22:30:23.510368Z","steps":["trace[1276554729] 'read index received' (duration: 558.298224ms)","trace[1276554729] 'applied index is now lower than readState.Index' (duration: 623.086024ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:23.528344Z","caller":"traceutil/trace.go:171","msg":"trace[1671923340] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"556.752867ms","start":"2026-04-09T22:30:22.971567Z","end":"2026-04-09T22:30:23.52832Z","steps":["trace[1671923340] 'process raft request' (duration: 556.624884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:23.528482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:22.971545Z","time spent":"556.869771ms","remote":"127.0.0.1:58182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T22:30:23.528774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181649295s","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-04-09T22:30:23.528814Z","caller":"traceutil/trace.go:171","msg":"trace[1884271026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"1.181716456s","start":"2026-04-09T22:30:22.347084Z","end":"2026-04-09T22:30:23.528801Z","steps":["trace[1884271026] 'agreement among raft nodes before linearized reading' (duration: 1.181608273s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:23.528838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:22.347072Z","time spent":"1.181759778s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T22:30:23.528847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079401228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:23.529047Z","caller":"traceutil/trace.go:171","msg":"trace[587890380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"1.079656665s","start":"2026-04-09T22:30:22.449352Z","end":"2026-04-09T22:30:23.529008Z","steps":["trace[587890380] 'agreement among raft nodes before linearized reading' (duration: 1.079345237s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:23.529103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.643369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-09T22:30:23.529203Z","caller":"traceutil/trace.go:171","msg":"trace[1256704821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"843.820274ms","start":"2026-04-09T22:30:22.685374Z","end":"2026-04-09T22:30:23.529194Z","steps":["trace[1256704821] 'agreement among raft nodes before linearized reading' (duration: 843.62473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:23.529227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:22.685341Z","time spent":"843.880306ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T22:30:23.529123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:22.449327Z","time spent":"1.079779908s","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T22:30:23.528835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186370597s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-09T22:30:23.529736Z","caller":"traceutil/trace.go:171","msg":"trace[738992461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"1.187329229s","start":"2026-04-09T22:30:22.342382Z","end":"2026-04-09T22:30:23.529711Z","steps":["trace[738992461] 'agreement among raft nodes before linearized reading' (duration: 1.186308605s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:23.529897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:22.342358Z","time spent":"1.187517754s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T22:30:24.03025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959726471113001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T22:30:25.498615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.670071857s","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-04-09T22:30:25.498758Z","caller":"traceutil/trace.go:171","msg":"trace[770174452] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:587; }","duration":"1.670235851s","start":"2026-04-09T22:30:23.8285Z","end":"2026-04-09T22:30:25.498736Z","steps":["trace[770174452] 'range keys from in-memory index tree' (duration: 1.670050027s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:25.499558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022835597s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:30:25.499912Z","caller":"traceutil/trace.go:171","msg":"trace[990428116] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.979793677s","start":"2026-04-09T22:30:23.520079Z","end":"2026-04-09T22:30:25.499873Z","steps":["trace[990428116] 'process raft request' (duration: 956.52669ms)","trace[990428116] 'compare' (duration: 1.021927885s)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:25.499981Z","caller":"traceutil/trace.go:171","msg":"trace[1086941000] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.962513983s","start":"2026-04-09T22:30:23.537445Z","end":"2026-04-09T22:30:25.499959Z","steps":["trace[1086941000] 'process raft request' (duration: 1.962423451s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:25.500069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:23.53742Z","time spent":"1.962609716s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:30:25.500097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:23.520039Z","time spent":"1.97995773s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:25.500143Z","caller":"traceutil/trace.go:171","msg":"trace[696074486] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.964991603s","start":"2026-04-09T22:30:23.535084Z","end":"2026-04-09T22:30:25.500076Z","steps":["trace[696074486] 'process raft request' (duration: 1.964674615s)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:25.500248Z","caller":"traceutil/trace.go:171","msg":"trace[750371454] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.962646906s","start":"2026-04-09T22:30:23.537582Z","end":"2026-04-09T22:30:25.500228Z","steps":["trace[750371454] 'process raft request' (duration: 1.962326989s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:25.500368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:23.537543Z","time spent":"1.962749499s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:30:25.500365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:23.53506Z","time spent":"1.965167236s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:25.500475Z","caller":"traceutil/trace.go:171","msg":"trace[617487632] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:611; }","duration":"1.970294119s","start":"2026-04-09T22:30:23.530167Z","end":"2026-04-09T22:30:25.500461Z","steps":["trace[617487632] 'read index received' (duration: 946.414347ms)","trace[617487632] 'applied index is now lower than readState.Index' (duration: 1.023878602s)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:25.500631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.970455393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:25.500716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.966738074s","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-04-09T22:30:25.500681Z","caller":"traceutil/trace.go:171","msg":"trace[242012016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"1.970589376s","start":"2026-04-09T22:30:23.530075Z","end":"2026-04-09T22:30:25.500664Z","steps":["trace[242012016] 'agreement among raft nodes before linearized reading' (duration: 1.970508104s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:25.500814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:23.530052Z","time spent":"1.97072785s","remote":"127.0.0.1:57918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T22:30:25.500868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.703433115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T22:30:25.500896Z","caller":"traceutil/trace.go:171","msg":"trace[2007841813] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:592; }","duration":"1.703520498s","start":"2026-04-09T22:30:23.797368Z","end":"2026-04-09T22:30:25.500889Z","steps":["trace[2007841813] 'agreement among raft nodes before linearized reading' (duration: 1.703411295s)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:25.500905Z","caller":"traceutil/trace.go:171","msg":"trace[1398240802] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.963243311s","start":"2026-04-09T22:30:23.537644Z","end":"2026-04-09T22:30:25.500888Z","steps":["trace[1398240802] 'process raft request' (duration: 1.962501933s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:25.500926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:23.797337Z","time spent":"1.70358203s","remote":"127.0.0.1:57946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"warn","ts":"2026-04-09T22:30:25.500982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:23.537633Z","time spent":"1.963308602s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:25.50075Z","caller":"traceutil/trace.go:171","msg":"trace[1915489456] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:592; }","duration":"1.966818855s","start":"2026-04-09T22:30:23.533919Z","end":"2026-04-09T22:30:25.500738Z","steps":["trace[1915489456] 'agreement among raft nodes before linearized reading' (duration: 1.966754194s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:25.501197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:23.533877Z","time spent":"1.967308957s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-09T22:30:25.651537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.795486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:25.651639Z","caller":"traceutil/trace.go:171","msg":"trace[551095535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"142.95176ms","start":"2026-04-09T22:30:25.508662Z","end":"2026-04-09T22:30:25.651614Z","steps":["trace[551095535] 'agreement among raft nodes before linearized reading' (duration: 90.408532ms)","trace[551095535] 'range keys from in-memory index tree' (duration: 52.373033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:25.651721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.765537ms","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-04-09T22:30:25.651869Z","caller":"traceutil/trace.go:171","msg":"trace[899913997] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:592; }","duration":"145.960291ms","start":"2026-04-09T22:30:25.505884Z","end":"2026-04-09T22:30:25.651844Z","steps":["trace[899913997] 'agreement among raft nodes before linearized reading' (duration: 93.220659ms)","trace[899913997] 'range keys from in-memory index tree' (duration: 52.518947ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:27.257311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.323546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:27.257405Z","caller":"traceutil/trace.go:171","msg":"trace[1848521449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:606; }","duration":"377.50306ms","start":"2026-04-09T22:30:26.879885Z","end":"2026-04-09T22:30:27.257388Z","steps":["trace[1848521449] 'range keys from in-memory index tree' (duration: 377.230924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:27.257445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:26.879864Z","time spent":"377.570962ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T22:30:43.949905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959726471113329,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T22:30:44.19226Z","caller":"traceutil/trace.go:171","msg":"trace[1078754004] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"796.816339ms","start":"2026-04-09T22:30:43.395413Z","end":"2026-04-09T22:30:44.19223Z","steps":["trace[1078754004] 'process raft request' (duration: 796.642995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:44.192413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:43.395364Z","time spent":"796.966372ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:44.193295Z","caller":"traceutil/trace.go:171","msg":"trace[573278668] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:784; }","duration":"744.025347ms","start":"2026-04-09T22:30:43.44923Z","end":"2026-04-09T22:30:44.193255Z","steps":["trace[573278668] 'read index received' (duration: 742.784437ms)","trace[573278668] 'applied index is now lower than readState.Index' (duration: 1.23891ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:44.193749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.524659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:44.193829Z","caller":"traceutil/trace.go:171","msg":"trace[846904758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"744.645182ms","start":"2026-04-09T22:30:43.449166Z","end":"2026-04-09T22:30:44.193811Z","steps":["trace[846904758] 'agreement among raft nodes before linearized reading' (duration: 744.518639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:44.193874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:43.449144Z","time spent":"744.723083ms","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T22:30:44.193931Z","caller":"traceutil/trace.go:171","msg":"trace[1802669002] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"403.952472ms","start":"2026-04-09T22:30:43.789965Z","end":"2026-04-09T22:30:44.193917Z","steps":["trace[1802669002] 'process raft request' (duration: 403.107513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:44.193933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.898398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T22:30:44.193995Z","caller":"traceutil/trace.go:171","msg":"trace[635520251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:757; }","duration":"579.066241ms","start":"2026-04-09T22:30:43.61491Z","end":"2026-04-09T22:30:44.193976Z","steps":["trace[635520251] 'agreement among raft nodes before linearized reading' (duration: 578.784244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:44.194019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:43.789925Z","time spent":"404.043555ms","remote":"127.0.0.1:58182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:30:44.194053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:43.614877Z","time spent":"579.164463ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T22:30:44.19427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.367212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:30:44.194327Z","caller":"traceutil/trace.go:171","msg":"trace[843072035] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:757; }","duration":"437.477684ms","start":"2026-04-09T22:30:43.75684Z","end":"2026-04-09T22:30:44.194318Z","steps":["trace[843072035] 'agreement among raft nodes before linearized reading' (duration: 437.400922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:44.194355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.249238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T22:30:44.194391Z","caller":"traceutil/trace.go:171","msg":"trace[1861318170] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:757; }","duration":"396.342959ms","start":"2026-04-09T22:30:43.798038Z","end":"2026-04-09T22:30:44.194381Z","steps":["trace[1861318170] 'agreement among raft nodes before linearized reading' (duration: 396.200066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:44.194373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:43.756812Z","time spent":"437.551476ms","remote":"127.0.0.1:58106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:30:44.194428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:43.798013Z","time spent":"396.403941ms","remote":"127.0.0.1:57946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"warn","ts":"2026-04-09T22:30:44.194414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.925891ms","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":"info","ts":"2026-04-09T22:30:44.194559Z","caller":"traceutil/trace.go:171","msg":"trace[990814129] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:757; }","duration":"539.129935ms","start":"2026-04-09T22:30:43.6554Z","end":"2026-04-09T22:30:44.19453Z","steps":["trace[990814129] 'agreement among raft nodes before linearized reading' (duration: 538.858929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:44.194555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.989488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-09T22:30:44.194555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.690615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:30:44.194637Z","caller":"traceutil/trace.go:171","msg":"trace[2085051196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:757; }","duration":"576.139411ms","start":"2026-04-09T22:30:43.618477Z","end":"2026-04-09T22:30:44.194616Z","steps":["trace[2085051196] 'agreement among raft nodes before linearized reading' (duration: 575.892055ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:44.194647Z","caller":"traceutil/trace.go:171","msg":"trace[954998062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:757; }","duration":"576.860318ms","start":"2026-04-09T22:30:43.617773Z","end":"2026-04-09T22:30:44.194633Z","steps":["trace[954998062] 'agreement among raft nodes before linearized reading' (duration: 576.579822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:44.19468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:43.618449Z","time spent":"576.223003ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T22:30:44.194683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:43.61774Z","time spent":"576.93363ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T22:30:44.194452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.708815ms","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-04-09T22:30:44.194762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:43.655382Z","time spent":"539.238168ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-04-09T22:30:44.194799Z","caller":"traceutil/trace.go:171","msg":"trace[2068902703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:757; }","duration":"577.082093ms","start":"2026-04-09T22:30:43.617705Z","end":"2026-04-09T22:30:44.194787Z","steps":["trace[2068902703] 'agreement among raft nodes before linearized reading' (duration: 576.655193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:44.194843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:43.617685Z","time spent":"577.147135ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T22:30:46.356789Z","caller":"traceutil/trace.go:171","msg":"trace[1563144492] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"141.74262ms","start":"2026-04-09T22:30:46.215011Z","end":"2026-04-09T22:30:46.356754Z","steps":["trace[1563144492] 'process raft request' (duration: 140.952112ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:46.357132Z","caller":"traceutil/trace.go:171","msg":"trace[1071843110] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"141.138146ms","start":"2026-04-09T22:30:46.215986Z","end":"2026-04-09T22:30:46.357124Z","steps":["trace[1071843110] 'process raft request' (duration: 140.778748ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:46.357112Z","caller":"traceutil/trace.go:171","msg":"trace[1284774313] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"140.718105ms","start":"2026-04-09T22:30:46.216377Z","end":"2026-04-09T22:30:46.357095Z","steps":["trace[1284774313] 'process raft request' (duration: 140.661384ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:46.357261Z","caller":"traceutil/trace.go:171","msg":"trace[1558662313] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"141.142966ms","start":"2026-04-09T22:30:46.216063Z","end":"2026-04-09T22:30:46.357206Z","steps":["trace[1558662313] 'process raft request' (duration: 140.90697ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:46.69704Z","caller":"traceutil/trace.go:171","msg":"trace[2003661314] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"183.822067ms","start":"2026-04-09T22:30:46.513189Z","end":"2026-04-09T22:30:46.697011Z","steps":["trace[2003661314] 'process raft request' (duration: 183.478089ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:48.113379Z","caller":"traceutil/trace.go:171","msg":"trace[98558113] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"223.742641ms","start":"2026-04-09T22:30:47.889612Z","end":"2026-04-09T22:30:48.113355Z","steps":["trace[98558113] 'process raft request' (duration: 223.579237ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:48.194409Z","caller":"traceutil/trace.go:171","msg":"trace[929070286] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"303.958132ms","start":"2026-04-09T22:30:47.890413Z","end":"2026-04-09T22:30:48.194372Z","steps":["trace[929070286] 'process raft request' (duration: 303.786638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:48.194806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:47.89038Z","time spent":"304.139125ms","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:30:48.335166Z","caller":"traceutil/trace.go:171","msg":"trace[2070374857] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"124.626331ms","start":"2026-04-09T22:30:48.210496Z","end":"2026-04-09T22:30:48.335122Z","steps":["trace[2070374857] 'read index received' (duration: 38.523471ms)","trace[2070374857] 'applied index is now lower than readState.Index' (duration: 86.10039ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:48.335201Z","caller":"traceutil/trace.go:171","msg":"trace[884903014] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"135.383938ms","start":"2026-04-09T22:30:48.199746Z","end":"2026-04-09T22:30:48.33513Z","steps":["trace[884903014] 'process raft request' (duration: 49.332399ms)","trace[884903014] 'compare' (duration: 85.405874ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:48.335881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.321087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-09T22:30:48.335943Z","caller":"traceutil/trace.go:171","msg":"trace[414494113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"125.488461ms","start":"2026-04-09T22:30:48.210438Z","end":"2026-04-09T22:30:48.335927Z","steps":["trace[414494113] 'agreement among raft nodes before linearized reading' (duration: 124.826535ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:48.471481Z","caller":"traceutil/trace.go:171","msg":"trace[237764336] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:811; }","duration":"110.163716ms","start":"2026-04-09T22:30:48.361282Z","end":"2026-04-09T22:30:48.471446Z","steps":["trace[237764336] 'read index received' (duration: 38.936312ms)","trace[237764336] 'applied index is now lower than readState.Index' (duration: 71.224634ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:48.471581Z","caller":"traceutil/trace.go:171","msg":"trace[1951363793] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"128.388361ms","start":"2026-04-09T22:30:48.343146Z","end":"2026-04-09T22:30:48.471534Z","steps":["trace[1951363793] 'process raft request' (duration: 115.724668ms)","trace[1951363793] 'compare' (duration: 12.366686ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:48.471854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.481393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-09T22:30:48.471903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.484413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-09T22:30:48.471955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.513904ms","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-04-09T22:30:48.472017Z","caller":"traceutil/trace.go:171","msg":"trace[1815100608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:783; }","duration":"110.652437ms","start":"2026-04-09T22:30:48.361308Z","end":"2026-04-09T22:30:48.471961Z","steps":["trace[1815100608] 'agreement among raft nodes before linearized reading' (duration: 110.386161ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:48.472071Z","caller":"traceutil/trace.go:171","msg":"trace[689706265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:783; }","duration":"110.631997ms","start":"2026-04-09T22:30:48.361392Z","end":"2026-04-09T22:30:48.472024Z","steps":["trace[689706265] 'agreement among raft nodes before linearized reading' (duration: 110.36495ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:48.472079Z","caller":"traceutil/trace.go:171","msg":"trace[2007467561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:783; }","duration":"110.713988ms","start":"2026-04-09T22:30:48.361338Z","end":"2026-04-09T22:30:48.472052Z","steps":["trace[2007467561] 'agreement among raft nodes before linearized reading' (duration: 110.38021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:48.471922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.572815ms","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-04-09T22:30:48.472259Z","caller":"traceutil/trace.go:171","msg":"trace[785679993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"111.008576ms","start":"2026-04-09T22:30:48.361234Z","end":"2026-04-09T22:30:48.472243Z","steps":["trace[785679993] 'agreement among raft nodes before linearized reading' (duration: 110.404161ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:57.76306Z","caller":"traceutil/trace.go:171","msg":"trace[1230007579] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"180.314013ms","start":"2026-04-09T22:30:57.582671Z","end":"2026-04-09T22:30:57.762985Z","steps":["trace[1230007579] 'process raft request' (duration: 180.067606ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:04.815656Z","caller":"traceutil/trace.go:171","msg":"trace[938949015] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:954; }","duration":"210.829023ms","start":"2026-04-09T22:31:04.604803Z","end":"2026-04-09T22:31:04.815632Z","steps":["trace[938949015] 'read index received' (duration: 63.840287ms)","trace[938949015] 'applied index is now lower than readState.Index' (duration: 146.987546ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:31:04.815763Z","caller":"traceutil/trace.go:171","msg":"trace[995547981] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"239.593541ms","start":"2026-04-09T22:31:04.576114Z","end":"2026-04-09T22:31:04.815707Z","steps":["trace[995547981] 'process raft request' (duration: 239.379115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:04.816029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.13034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-09T22:31:04.816179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.1217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:31:04.816226Z","caller":"traceutil/trace.go:171","msg":"trace[1203441110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:920; }","duration":"211.434607ms","start":"2026-04-09T22:31:04.604709Z","end":"2026-04-09T22:31:04.816144Z","steps":["trace[1203441110] 'agreement among raft nodes before linearized reading' (duration: 211.093779ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:04.816276Z","caller":"traceutil/trace.go:171","msg":"trace[1711684737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:920; }","duration":"211.247653ms","start":"2026-04-09T22:31:04.605012Z","end":"2026-04-09T22:31:04.81626Z","steps":["trace[1711684737] 'agreement among raft nodes before linearized reading' (duration: 210.992957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:04.816539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.25889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T22:31:04.81669Z","caller":"traceutil/trace.go:171","msg":"trace[1532555014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:920; }","duration":"210.459004ms","start":"2026-04-09T22:31:04.606195Z","end":"2026-04-09T22:31:04.816654Z","steps":["trace[1532555014] 'agreement among raft nodes before linearized reading' (duration: 210.040974ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:14.87819Z","caller":"traceutil/trace.go:171","msg":"trace[937269929] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"155.445818ms","start":"2026-04-09T22:31:14.722713Z","end":"2026-04-09T22:31:14.878159Z","steps":["trace[937269929] 'process raft request' (duration: 155.256863ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:14.99972Z","caller":"traceutil/trace.go:171","msg":"trace[379801046] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"100.805011ms","start":"2026-04-09T22:31:14.898873Z","end":"2026-04-09T22:31:14.999678Z","steps":["trace[379801046] 'process raft request' (duration: 62.565707ms)","trace[379801046] 'compare' (duration: 38.03961ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:31:20.505663Z","caller":"traceutil/trace.go:171","msg":"trace[625339580] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"319.069916ms","start":"2026-04-09T22:31:20.186535Z","end":"2026-04-09T22:31:20.505605Z","steps":["trace[625339580] 'process raft request' (duration: 318.798659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:20.505925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:20.186507Z","time spent":"319.276231ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:31:21.07062Z","caller":"traceutil/trace.go:171","msg":"trace[969450071] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"154.125485ms","start":"2026-04-09T22:31:20.916467Z","end":"2026-04-09T22:31:21.070593Z","steps":["trace[969450071] 'read index received' (duration: 153.961941ms)","trace[969450071] 'applied index is now lower than readState.Index' (duration: 162.494µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:31:21.070926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.443703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-09T22:31:21.070887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.493291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-09T22:31:21.071003Z","caller":"traceutil/trace.go:171","msg":"trace[1544070115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"154.580996ms","start":"2026-04-09T22:31:20.916405Z","end":"2026-04-09T22:31:21.070986Z","steps":["trace[1544070115] 'agreement among raft nodes before linearized reading' (duration: 154.32556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:21.071064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.761296ms","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-04-09T22:31:21.071154Z","caller":"traceutil/trace.go:171","msg":"trace[1566156776] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:976; }","duration":"108.94783ms","start":"2026-04-09T22:31:20.962184Z","end":"2026-04-09T22:31:21.071131Z","steps":["trace[1566156776] 'agreement among raft nodes before linearized reading' (duration: 108.636093ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:21.071023Z","caller":"traceutil/trace.go:171","msg":"trace[794933249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:976; }","duration":"117.704957ms","start":"2026-04-09T22:31:20.953304Z","end":"2026-04-09T22:31:21.071009Z","steps":["trace[794933249] 'agreement among raft nodes before linearized reading' (duration: 117.44388ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:21.070679Z","caller":"traceutil/trace.go:171","msg":"trace[539145059] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"165.523624ms","start":"2026-04-09T22:31:20.905119Z","end":"2026-04-09T22:31:21.070643Z","steps":["trace[539145059] 'process raft request' (duration: 165.284218ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:47.495484Z","caller":"traceutil/trace.go:171","msg":"trace[1893312503] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"196.460404ms","start":"2026-04-09T22:31:47.299Z","end":"2026-04-09T22:31:47.49546Z","steps":["trace[1893312503] 'read index received' (duration: 196.242079ms)","trace[1893312503] 'applied index is now lower than readState.Index' (duration: 217.255µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:31:47.495583Z","caller":"traceutil/trace.go:171","msg":"trace[1263441957] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"265.191684ms","start":"2026-04-09T22:31:47.230363Z","end":"2026-04-09T22:31:47.495554Z","steps":["trace[1263441957] 'process raft request' (duration: 264.963809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:47.495702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.827454ms","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-04-09T22:31:47.495737Z","caller":"traceutil/trace.go:171","msg":"trace[472469569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1061; }","duration":"116.915607ms","start":"2026-04-09T22:31:47.378811Z","end":"2026-04-09T22:31:47.495727Z","steps":["trace[472469569] 'agreement among raft nodes before linearized reading' (duration: 116.778793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:47.495733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.833366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:31:47.495778Z","caller":"traceutil/trace.go:171","msg":"trace[2144116378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"116.895927ms","start":"2026-04-09T22:31:47.37887Z","end":"2026-04-09T22:31:47.495766Z","steps":["trace[2144116378] 'agreement among raft nodes before linearized reading' (duration: 116.783464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:47.495839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.649211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-09T22:31:47.495906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.903485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-09T22:31:47.495934Z","caller":"traceutil/trace.go:171","msg":"trace[141028493] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"196.958506ms","start":"2026-04-09T22:31:47.298967Z","end":"2026-04-09T22:31:47.495925Z","steps":["trace[141028493] 'agreement among raft nodes before linearized reading' (duration: 196.610467ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:47.495936Z","caller":"traceutil/trace.go:171","msg":"trace[505154332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1061; }","duration":"116.773045ms","start":"2026-04-09T22:31:47.379136Z","end":"2026-04-09T22:31:47.495909Z","steps":["trace[505154332] 'agreement among raft nodes before linearized reading' (duration: 116.491557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:47.869045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.047698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:31:47.869161Z","caller":"traceutil/trace.go:171","msg":"trace[1082753464] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"370.106969ms","start":"2026-04-09T22:31:47.499029Z","end":"2026-04-09T22:31:47.869136Z","steps":["trace[1082753464] 'process raft request' (duration: 228.876348ms)","trace[1082753464] 'compare' (duration: 140.835142ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:31:47.869223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:47.499006Z","time spent":"370.189142ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:31:47.912059Z","caller":"traceutil/trace.go:171","msg":"trace[1239611983] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"411.907916ms","start":"2026-04-09T22:31:47.50013Z","end":"2026-04-09T22:31:47.912038Z","steps":["trace[1239611983] 'process raft request' (duration: 411.861755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:47.912199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:47.500114Z","time spent":"412.018878ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:31:47.912344Z","caller":"traceutil/trace.go:171","msg":"trace[1498072838] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"410.545923ms","start":"2026-04-09T22:31:47.501762Z","end":"2026-04-09T22:31:47.912308Z","steps":["trace[1498072838] 'process raft request' (duration: 410.254776ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:47.912443Z","caller":"traceutil/trace.go:171","msg":"trace[1941232322] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"412.938099ms","start":"2026-04-09T22:31:47.499495Z","end":"2026-04-09T22:31:47.912433Z","steps":["trace[1941232322] 'process raft request' (duration: 412.352805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:47.912485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:47.499469Z","time spent":"412.997601ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:31:47.912978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:47.501748Z","time spent":"410.676706ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:31:49.225427Z","caller":"traceutil/trace.go:171","msg":"trace[1375537093] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"401.905889ms","start":"2026-04-09T22:31:48.823493Z","end":"2026-04-09T22:31:49.225399Z","steps":["trace[1375537093] 'read index received' (duration: 401.827377ms)","trace[1375537093] 'applied index is now lower than readState.Index' (duration: 77.742µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:31:49.225578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.062313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:31:49.22561Z","caller":"traceutil/trace.go:171","msg":"trace[1312340235] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1068; }","duration":"402.160325ms","start":"2026-04-09T22:31:48.823441Z","end":"2026-04-09T22:31:49.225602Z","steps":["trace[1312340235] 'agreement among raft nodes before linearized reading' (duration: 402.059533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:49.225658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:48.823417Z","time spent":"402.230837ms","remote":"127.0.0.1:58074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-04-09T22:31:49.225764Z","caller":"traceutil/trace.go:171","msg":"trace[274727224] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"590.1563ms","start":"2026-04-09T22:31:48.635519Z","end":"2026-04-09T22:31:49.225675Z","steps":["trace[274727224] 'process raft request' (duration: 589.701669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:49.226156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:48.635486Z","time spent":"590.500257ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:31:50.400977Z","caller":"traceutil/trace.go:171","msg":"trace[988605396] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"443.926021ms","start":"2026-04-09T22:31:49.957018Z","end":"2026-04-09T22:31:50.400944Z","steps":["trace[988605396] 'process raft request' (duration: 443.748357ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:50.401039Z","caller":"traceutil/trace.go:171","msg":"trace[860412606] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"444.458293ms","start":"2026-04-09T22:31:49.956544Z","end":"2026-04-09T22:31:50.401002Z","steps":["trace[860412606] 'process raft request' (duration: 366.199237ms)","trace[860412606] 'compare' (duration: 77.501778ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:31:50.401189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:49.956988Z","time spent":"444.106456ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:31:50.401189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:49.956518Z","time spent":"444.574937ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:31:51.950008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959726471114064,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T22:31:52.302393Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.068916781s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T22:31:52.302983Z","caller":"traceutil/trace.go:171","msg":"trace[1255825987] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1119; }","duration":"853.092282ms","start":"2026-04-09T22:31:51.449857Z","end":"2026-04-09T22:31:52.30295Z","steps":["trace[1255825987] 'read index received' (duration: 852.715802ms)","trace[1255825987] 'applied index is now lower than readState.Index' (duration: 375.46µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:31:52.303131Z","caller":"traceutil/trace.go:171","msg":"trace[617743699] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"1.06973069s","start":"2026-04-09T22:31:51.233325Z","end":"2026-04-09T22:31:52.303055Z","steps":["trace[617743699] 'process raft request' (duration: 1.06931253s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:52.303213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.319827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:31:52.303312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.679693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:31:52.303344Z","caller":"traceutil/trace.go:171","msg":"trace[1918759254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1074; }","duration":"853.526342ms","start":"2026-04-09T22:31:51.449793Z","end":"2026-04-09T22:31:52.303319Z","steps":["trace[1918759254] 'agreement among raft nodes before linearized reading' (duration: 853.305796ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:52.303381Z","caller":"traceutil/trace.go:171","msg":"trace[42352551] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1074; }","duration":"763.842017ms","start":"2026-04-09T22:31:51.539525Z","end":"2026-04-09T22:31:52.303367Z","steps":["trace[42352551] 'agreement among raft nodes before linearized reading' (duration: 763.651142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:52.303411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:51.449767Z","time spent":"853.634454ms","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T22:31:52.303438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:51.53945Z","time spent":"763.976309ms","remote":"127.0.0.1:58392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:31:52.303464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.283895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-09T22:31:52.303466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:51.233293Z","time spent":"1.070016367s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:31:52.303509Z","caller":"traceutil/trace.go:171","msg":"trace[1921038877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1074; }","duration":"348.378888ms","start":"2026-04-09T22:31:51.955116Z","end":"2026-04-09T22:31:52.303495Z","steps":["trace[1921038877] 'agreement among raft nodes before linearized reading' (duration: 348.198013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:52.303544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:51.955081Z","time spent":"348.45633ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T22:31:52.303554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.103344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T22:31:52.303622Z","caller":"traceutil/trace.go:171","msg":"trace[1625941370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"307.220576ms","start":"2026-04-09T22:31:51.996383Z","end":"2026-04-09T22:31:52.303604Z","steps":["trace[1625941370] 'agreement among raft nodes before linearized reading' (duration: 306.802026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:52.303603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.711091ms","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-04-09T22:31:52.303666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:51.996359Z","time spent":"307.296868ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T22:31:52.303692Z","caller":"traceutil/trace.go:171","msg":"trace[1442234325] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1074; }","duration":"718.811294ms","start":"2026-04-09T22:31:51.584862Z","end":"2026-04-09T22:31:52.303673Z","steps":["trace[1442234325] 'agreement among raft nodes before linearized reading' (duration: 718.571028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:52.303703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.517924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:31:52.303738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:51.584803Z","time spent":"718.924147ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T22:31:52.303741Z","caller":"traceutil/trace.go:171","msg":"trace[1470118419] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1074; }","duration":"285.593176ms","start":"2026-04-09T22:31:52.018135Z","end":"2026-04-09T22:31:52.303728Z","steps":["trace[1470118419] 'agreement among raft nodes before linearized reading' (duration: 285.530335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:53.143718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.716092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:31:53.144046Z","caller":"traceutil/trace.go:171","msg":"trace[19567638] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1120; }","duration":"737.971406ms","start":"2026-04-09T22:31:52.406052Z","end":"2026-04-09T22:31:53.144023Z","steps":["trace[19567638] 'read index received' (duration: 160.868135ms)","trace[19567638] 'applied index is now lower than readState.Index' (duration: 577.102231ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:31:53.14415Z","caller":"traceutil/trace.go:171","msg":"trace[1630074422] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"834.878071ms","start":"2026-04-09T22:31:52.30926Z","end":"2026-04-09T22:31:53.144138Z","steps":["trace[1630074422] 'process raft request' (duration: 257.638806ms)","trace[1630074422] 'compare' (duration: 576.481847ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:31:53.144218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:52.309244Z","time spent":"834.935433ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:31:53.144379Z","caller":"traceutil/trace.go:171","msg":"trace[1293155382] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"833.372096ms","start":"2026-04-09T22:31:52.310975Z","end":"2026-04-09T22:31:53.144347Z","steps":["trace[1293155382] 'process raft request' (duration: 833.000588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:53.144632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:52.310954Z","time spent":"833.504969ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:31:53.145175Z","caller":"traceutil/trace.go:171","msg":"trace[974994959] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"835.405853ms","start":"2026-04-09T22:31:52.30975Z","end":"2026-04-09T22:31:53.145156Z","steps":["trace[974994959] 'process raft request' (duration: 834.091243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:53.146103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:52.309718Z","time spent":"836.336465ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:31:53.146232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.031007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:31:53.146264Z","caller":"traceutil/trace.go:171","msg":"trace[742607014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1077; }","duration":"696.061318ms","start":"2026-04-09T22:31:52.450192Z","end":"2026-04-09T22:31:53.146253Z","steps":["trace[742607014] 'agreement among raft nodes before linearized reading' (duration: 696.005396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:53.146292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:52.450165Z","time spent":"696.121679ms","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T22:31:53.146565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.703446ms","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-04-09T22:31:53.146651Z","caller":"traceutil/trace.go:171","msg":"trace[1975495093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1077; }","duration":"739.84528ms","start":"2026-04-09T22:31:52.406786Z","end":"2026-04-09T22:31:53.146631Z","steps":["trace[1975495093] 'agreement among raft nodes before linearized reading' (duration: 737.608397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:53.146698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:52.406766Z","time spent":"739.920932ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T22:31:53.147366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.290435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-09T22:31:53.147464Z","caller":"traceutil/trace.go:171","msg":"trace[1067376026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1077; }","duration":"741.462938ms","start":"2026-04-09T22:31:52.405978Z","end":"2026-04-09T22:31:53.147441Z","steps":["trace[1067376026] 'agreement among raft nodes before linearized reading' (duration: 738.418187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:53.147518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:52.405952Z","time spent":"741.556351ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-09T22:31:53.487607Z","caller":"traceutil/trace.go:171","msg":"trace[1399519862] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"335.79244ms","start":"2026-04-09T22:31:53.151769Z","end":"2026-04-09T22:31:53.487561Z","steps":["trace[1399519862] 'process raft request' (duration: 299.402672ms)","trace[1399519862] 'compare' (duration: 36.183293ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:31:53.488059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:53.151743Z","time spent":"335.970753ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:31:53.492515Z","caller":"traceutil/trace.go:171","msg":"trace[640161792] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"339.404345ms","start":"2026-04-09T22:31:53.153071Z","end":"2026-04-09T22:31:53.492475Z","steps":["trace[640161792] 'process raft request' (duration: 339.237841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:53.492719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:53.153048Z","time spent":"339.556349ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:32:07.817965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959726471114287,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T22:32:07.995099Z","caller":"traceutil/trace.go:171","msg":"trace[1205810338] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1223; }","duration":"677.732075ms","start":"2026-04-09T22:32:07.317277Z","end":"2026-04-09T22:32:07.995009Z","steps":["trace[1205810338] 'read index received' (duration: 677.559161ms)","trace[1205810338] 'applied index is now lower than readState.Index' (duration: 171.754µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:07.995109Z","caller":"traceutil/trace.go:171","msg":"trace[467988144] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"678.991814ms","start":"2026-04-09T22:32:07.316064Z","end":"2026-04-09T22:32:07.995056Z","steps":["trace[467988144] 'process raft request' (duration: 678.788159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:07.995302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:07.316037Z","time spent":"679.162668ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:32:07.995332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.030592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T22:32:07.995369Z","caller":"traceutil/trace.go:171","msg":"trace[1188023679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1173; }","duration":"678.092583ms","start":"2026-04-09T22:32:07.317263Z","end":"2026-04-09T22:32:07.995356Z","steps":["trace[1188023679] 'agreement among raft nodes before linearized reading' (duration: 677.94424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:07.995409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:07.317256Z","time spent":"678.144705ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T22:32:07.995473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.866328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:32:07.995567Z","caller":"traceutil/trace.go:171","msg":"trace[432364743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"677.97554ms","start":"2026-04-09T22:32:07.317571Z","end":"2026-04-09T22:32:07.995546Z","steps":["trace[432364743] 'agreement among raft nodes before linearized reading' (duration: 677.766106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:07.995579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.432839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-09T22:32:07.995611Z","caller":"traceutil/trace.go:171","msg":"trace[979377611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1173; }","duration":"407.492251ms","start":"2026-04-09T22:32:07.58811Z","end":"2026-04-09T22:32:07.995602Z","steps":["trace[979377611] 'agreement among raft nodes before linearized reading' (duration: 407.407129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:07.99561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:07.317568Z","time spent":"678.033772ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T22:32:07.995637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:07.588092Z","time spent":"407.538751ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T22:32:07.995745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.504095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:32:07.995785Z","caller":"traceutil/trace.go:171","msg":"trace[62665519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1173; }","duration":"545.580777ms","start":"2026-04-09T22:32:07.450195Z","end":"2026-04-09T22:32:07.995776Z","steps":["trace[62665519] 'agreement among raft nodes before linearized reading' (duration: 545.506245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:07.995778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.260261ms","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-04-09T22:32:07.995813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:07.450176Z","time spent":"545.631468ms","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T22:32:07.995857Z","caller":"traceutil/trace.go:171","msg":"trace[1311930230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1173; }","duration":"361.401244ms","start":"2026-04-09T22:32:07.634432Z","end":"2026-04-09T22:32:07.995834Z","steps":["trace[1311930230] 'agreement among raft nodes before linearized reading' (duration: 361.123788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:07.995919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:07.634407Z","time spent":"361.502297ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-09T22:32:13.956944Z","caller":"traceutil/trace.go:171","msg":"trace[1969214091] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"138.472145ms","start":"2026-04-09T22:32:13.818433Z","end":"2026-04-09T22:32:13.956905Z","steps":["trace[1969214091] 'process raft request' (duration: 108.277144ms)","trace[1969214091] 'compare' (duration: 30.022987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:32:24.663708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.362985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-09T22:32:24.663792Z","caller":"traceutil/trace.go:171","msg":"trace[1034174759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1237; }","duration":"447.472778ms","start":"2026-04-09T22:32:24.2163Z","end":"2026-04-09T22:32:24.663773Z","steps":["trace[1034174759] 'agreement among raft nodes before linearized reading' (duration: 60.891544ms)","trace[1034174759] 'range keys from in-memory index tree' (duration: 386.44481ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:32:24.663831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:24.216291Z","time spent":"447.532309ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T22:32:24.664268Z","caller":"traceutil/trace.go:171","msg":"trace[582927934] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"159.517923ms","start":"2026-04-09T22:32:24.504715Z","end":"2026-04-09T22:32:24.664233Z","steps":["trace[582927934] 'process raft request' (duration: 135.525312ms)","trace[582927934] 'compare' (duration: 23.884548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:32:24.664424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.334398ms","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-04-09T22:32:24.664518Z","caller":"traceutil/trace.go:171","msg":"trace[1639677479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1237; }","duration":"450.44854ms","start":"2026-04-09T22:32:24.214051Z","end":"2026-04-09T22:32:24.664499Z","steps":["trace[1639677479] 'agreement among raft nodes before linearized reading' (duration: 63.117628ms)","trace[1639677479] 'range keys from in-memory index tree' (duration: 386.255146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:32:24.664574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:24.214041Z","time spent":"450.519832ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-09T22:32:28.342974Z","caller":"traceutil/trace.go:171","msg":"trace[801492347] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"200.145888ms","start":"2026-04-09T22:32:28.142793Z","end":"2026-04-09T22:32:28.342938Z","steps":["trace[801492347] 'process raft request' (duration: 199.564483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:28.698704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.205362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:32:28.69888Z","caller":"traceutil/trace.go:171","msg":"trace[644007192] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"351.190666ms","start":"2026-04-09T22:32:28.347655Z","end":"2026-04-09T22:32:28.698845Z","steps":["trace[644007192] 'process raft request' (duration: 199.734498ms)","trace[644007192] 'compare' (duration: 150.930535ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:28.698946Z","caller":"traceutil/trace.go:171","msg":"trace[797858030] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"252.804764ms","start":"2026-04-09T22:32:28.446115Z","end":"2026-04-09T22:32:28.698919Z","steps":["trace[797858030] 'read index received' (duration: 101.205552ms)","trace[797858030] 'applied index is now lower than readState.Index' (duration: 151.594882ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:32:28.699066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:28.347624Z","time spent":"351.32812ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:32:28.699114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.986168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:32:28.69915Z","caller":"traceutil/trace.go:171","msg":"trace[1672426065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"253.07349ms","start":"2026-04-09T22:32:28.446066Z","end":"2026-04-09T22:32:28.699139Z","steps":["trace[1672426065] 'agreement among raft nodes before linearized reading' (duration: 252.996878ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:28.904511Z","caller":"traceutil/trace.go:171","msg":"trace[1987020000] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"174.385014ms","start":"2026-04-09T22:32:28.73009Z","end":"2026-04-09T22:32:28.904475Z","steps":["trace[1987020000] 'process raft request' (duration: 174.199679ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:28.917603Z","caller":"traceutil/trace.go:171","msg":"trace[1572219840] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"186.010216ms","start":"2026-04-09T22:32:28.731553Z","end":"2026-04-09T22:32:28.917563Z","steps":["trace[1572219840] 'process raft request' (duration: 185.925494ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:29.889696Z","caller":"traceutil/trace.go:171","msg":"trace[1501564725] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"363.508455ms","start":"2026-04-09T22:32:29.526165Z","end":"2026-04-09T22:32:29.889674Z","steps":["trace[1501564725] 'process raft request' (duration: 363.272059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:29.889839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:29.526148Z","time spent":"363.628937ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T22:32:30.581302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.48861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:32:30.581506Z","caller":"traceutil/trace.go:171","msg":"trace[571916525] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"685.372631ms","start":"2026-04-09T22:32:29.896115Z","end":"2026-04-09T22:32:30.581488Z","steps":["trace[571916525] 'process raft request' (duration: 685.293099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:30.581563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:29.896095Z","time spent":"685.447782ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:32:30.581669Z","caller":"traceutil/trace.go:171","msg":"trace[1063392258] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"1.018399827s","start":"2026-04-09T22:32:29.563215Z","end":"2026-04-09T22:32:30.581615Z","steps":["trace[1063392258] 'process raft request' (duration: 655.523127ms)","trace[1063392258] 'compare' (duration: 362.254355ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:30.581773Z","caller":"traceutil/trace.go:171","msg":"trace[2130262075] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1338; }","duration":"743.517479ms","start":"2026-04-09T22:32:29.838246Z","end":"2026-04-09T22:32:30.581764Z","steps":["trace[2130262075] 'read index received' (duration: 51.285603ms)","trace[2130262075] 'applied index is now lower than readState.Index' (duration: 692.230846ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:32:30.581861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:29.563193Z","time spent":"1.018592702s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T22:32:30.581994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.750124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-09T22:32:30.582023Z","caller":"traceutil/trace.go:171","msg":"trace[2016019648] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1285; }","duration":"743.856787ms","start":"2026-04-09T22:32:29.838158Z","end":"2026-04-09T22:32:30.582015Z","steps":["trace[2016019648] 'agreement among raft nodes before linearized reading' (duration: 743.720594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:30.582046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:29.83813Z","time spent":"743.910499ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-09T22:32:30.582202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.132548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-09T22:32:30.582229Z","caller":"traceutil/trace.go:171","msg":"trace[924006042] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1285; }","duration":"688.220369ms","start":"2026-04-09T22:32:29.894002Z","end":"2026-04-09T22:32:30.582223Z","steps":["trace[924006042] 'agreement among raft nodes before linearized reading' (duration: 688.154758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:30.582248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:29.89393Z","time spent":"688.313042ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-09T22:32:30.582268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.57805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:32:30.582319Z","caller":"traceutil/trace.go:171","msg":"trace[2035186054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1285; }","duration":"134.674582ms","start":"2026-04-09T22:32:30.447631Z","end":"2026-04-09T22:32:30.582306Z","steps":["trace[2035186054] 'agreement among raft nodes before linearized reading' (duration: 134.58887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:30.582381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.053839ms","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-04-09T22:32:30.582405Z","caller":"traceutil/trace.go:171","msg":"trace[1851898433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"196.1256ms","start":"2026-04-09T22:32:30.386273Z","end":"2026-04-09T22:32:30.582399Z","steps":["trace[1851898433] 'agreement among raft nodes before linearized reading' (duration: 196.061589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:30.582534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.544561ms","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-04-09T22:32:30.58256Z","caller":"traceutil/trace.go:171","msg":"trace[2022419102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1285; }","duration":"234.612042ms","start":"2026-04-09T22:32:30.347942Z","end":"2026-04-09T22:32:30.582554Z","steps":["trace[2022419102] 'agreement among raft nodes before linearized reading' (duration: 234.552281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:30.582678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.94062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:32:30.582703Z","caller":"traceutil/trace.go:171","msg":"trace[978698664] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1285; }","duration":"447.966841ms","start":"2026-04-09T22:32:30.134728Z","end":"2026-04-09T22:32:30.582695Z","steps":["trace[978698664] 'agreement among raft nodes before linearized reading' (duration: 447.92749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:30.582725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:30.134665Z","time spent":"448.052922ms","remote":"127.0.0.1:57956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:32:30.582856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.979236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:32:30.582903Z","caller":"traceutil/trace.go:171","msg":"trace[2080965746] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1285; }","duration":"627.072568ms","start":"2026-04-09T22:32:29.955819Z","end":"2026-04-09T22:32:30.582892Z","steps":["trace[2080965746] 'agreement among raft nodes before linearized reading' (duration: 626.999276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:30.582935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:29.95579Z","time spent":"627.13551ms","remote":"127.0.0.1:48166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-04-09T22:32:30.775428Z","caller":"traceutil/trace.go:171","msg":"trace[1948995713] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"187.520432ms","start":"2026-04-09T22:32:30.587864Z","end":"2026-04-09T22:32:30.775384Z","steps":["trace[1948995713] 'process raft request' (duration: 140.992794ms)","trace[1948995713] 'compare' (duration: 46.113618ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:30.775594Z","caller":"traceutil/trace.go:171","msg":"trace[2095290037] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"186.161849ms","start":"2026-04-09T22:32:30.589422Z","end":"2026-04-09T22:32:30.775583Z","steps":["trace[2095290037] 'process raft request' (duration: 186.013255ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:30.775514Z","caller":"traceutil/trace.go:171","msg":"trace[778700129] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"187.298136ms","start":"2026-04-09T22:32:30.588193Z","end":"2026-04-09T22:32:30.775491Z","steps":["trace[778700129] 'process raft request' (duration: 187.197604ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:30.775515Z","caller":"traceutil/trace.go:171","msg":"trace[647976767] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"187.321047ms","start":"2026-04-09T22:32:30.58817Z","end":"2026-04-09T22:32:30.775491Z","steps":["trace[647976767] 'process raft request' (duration: 187.103692ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:31.095736Z","caller":"traceutil/trace.go:171","msg":"trace[570348149] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"172.546459ms","start":"2026-04-09T22:32:30.923166Z","end":"2026-04-09T22:32:31.095713Z","steps":["trace[570348149] 'read index received' (duration: 172.285812ms)","trace[570348149] 'applied index is now lower than readState.Index' (duration: 259.447µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:31.095928Z","caller":"traceutil/trace.go:171","msg":"trace[2045662095] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"178.254427ms","start":"2026-04-09T22:32:30.917636Z","end":"2026-04-09T22:32:31.095891Z","steps":["trace[2045662095] 'process raft request' (duration: 177.896528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:31.096269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.063741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T22:32:31.096369Z","caller":"traceutil/trace.go:171","msg":"trace[1115601176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"173.206906ms","start":"2026-04-09T22:32:30.923138Z","end":"2026-04-09T22:32:31.096345Z","steps":["trace[1115601176] 'agreement among raft nodes before linearized reading' (duration: 172.674942ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:32.3213Z","caller":"traceutil/trace.go:171","msg":"trace[1037608454] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"241.272133ms","start":"2026-04-09T22:32:32.080006Z","end":"2026-04-09T22:32:32.321278Z","steps":["trace[1037608454] 'read index received' (duration: 241.065508ms)","trace[1037608454] 'applied index is now lower than readState.Index' (duration: 205.705µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:32.321349Z","caller":"traceutil/trace.go:171","msg":"trace[1867570661] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"602.019891ms","start":"2026-04-09T22:32:31.719309Z","end":"2026-04-09T22:32:32.321329Z","steps":["trace[1867570661] 'process raft request' (duration: 601.829107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:32.321452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.436047ms","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":"warn","ts":"2026-04-09T22:32:32.321475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:31.719286Z","time spent":"602.117994ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:32:32.321487Z","caller":"traceutil/trace.go:171","msg":"trace[1443574859] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1295; }","duration":"241.521749ms","start":"2026-04-09T22:32:32.079954Z","end":"2026-04-09T22:32:32.321476Z","steps":["trace[1443574859] 'agreement among raft nodes before linearized reading' (duration: 241.407806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:32.962163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.908792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:32:32.962257Z","caller":"traceutil/trace.go:171","msg":"trace[1901000234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1296; }","duration":"181.051356ms","start":"2026-04-09T22:32:32.781182Z","end":"2026-04-09T22:32:32.962234Z","steps":["trace[1901000234] 'range keys from in-memory index tree' (duration: 180.654485ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:33.012384Z","caller":"traceutil/trace.go:171","msg":"trace[1175432932] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"211.193145ms","start":"2026-04-09T22:32:32.80117Z","end":"2026-04-09T22:32:33.012363Z","steps":["trace[1175432932] 'read index received' (duration: 211.041311ms)","trace[1175432932] 'applied index is now lower than readState.Index' (duration: 150.744µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:33.012434Z","caller":"traceutil/trace.go:171","msg":"trace[661543259] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"230.913772ms","start":"2026-04-09T22:32:32.781491Z","end":"2026-04-09T22:32:33.012405Z","steps":["trace[661543259] 'process raft request' (duration: 230.723417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:33.012579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.39547ms","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-04-09T22:32:33.012606Z","caller":"traceutil/trace.go:171","msg":"trace[1561993326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1297; }","duration":"211.466902ms","start":"2026-04-09T22:32:32.801132Z","end":"2026-04-09T22:32:33.012599Z","steps":["trace[1561993326] 'agreement among raft nodes before linearized reading' (duration: 211.310918ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:33.120897Z","caller":"traceutil/trace.go:171","msg":"trace[606128479] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"103.432775ms","start":"2026-04-09T22:32:33.017442Z","end":"2026-04-09T22:32:33.120875Z","steps":["trace[606128479] 'process raft request' (duration: 103.119378ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:38.63084Z","caller":"traceutil/trace.go:171","msg":"trace[1239637237] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"148.609189ms","start":"2026-04-09T22:32:38.482206Z","end":"2026-04-09T22:32:38.630815Z","steps":["trace[1239637237] 'process raft request' (duration: 148.458696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:39.631382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959726471114639,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T22:32:40.131935Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959726471114639,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T22:32:40.187603Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.160466186s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-09T22:32:40.565324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.796979155s","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-04-09T22:32:40.565394Z","caller":"traceutil/trace.go:171","msg":"trace[434088369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1338; }","duration":"1.797061507s","start":"2026-04-09T22:32:38.768315Z","end":"2026-04-09T22:32:40.565377Z","steps":["trace[434088369] 'range keys from in-memory index tree' (duration: 1.796822721s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:40.565424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:38.76825Z","time spent":"1.797167099s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T22:32:40.565816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.385931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:32:40.566387Z","caller":"traceutil/trace.go:171","msg":"trace[164413923] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"1.782003301s","start":"2026-04-09T22:32:38.78437Z","end":"2026-04-09T22:32:40.566374Z","steps":["trace[164413923] 'process raft request' (duration: 1.403585425s)","trace[164413923] 'compare' (duration: 377.281118ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:32:40.566516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:38.784348Z","time spent":"1.782074273s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:32:40.700259Z","caller":"traceutil/trace.go:171","msg":"trace[569644176] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"1.639226562s","start":"2026-04-09T22:32:39.060977Z","end":"2026-04-09T22:32:40.700203Z","steps":["trace[569644176] 'process raft request' (duration: 1.638436422s)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:40.700346Z","caller":"traceutil/trace.go:171","msg":"trace[1895525544] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"1.612089436s","start":"2026-04-09T22:32:39.088235Z","end":"2026-04-09T22:32:40.700325Z","steps":["trace[1895525544] 'process raft request' (duration: 1.61185164s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:40.700482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:39.0882Z","time spent":"1.612181049s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:32:40.700481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:39.060947Z","time spent":"1.639431797s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:32:40.83855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.384241ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T22:32:40.838795Z","caller":"traceutil/trace.go:171","msg":"trace[55582956] linearizableReadLoop","detail":"{readStateIndex:1399; appliedIndex:1395; }","duration":"1.707598588s","start":"2026-04-09T22:32:39.131174Z","end":"2026-04-09T22:32:40.838772Z","steps":["trace[55582956] 'read index received' (duration: 1.056630541s)","trace[55582956] 'applied index is now lower than readState.Index' (duration: 650.966837ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:32:40.839016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707840554s","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-04-09T22:32:40.839089Z","caller":"traceutil/trace.go:171","msg":"trace[175676304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1341; }","duration":"1.707960197s","start":"2026-04-09T22:32:39.131118Z","end":"2026-04-09T22:32:40.839078Z","steps":["trace[175676304] 'agreement among raft nodes before linearized reading' (duration: 1.707771443s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:40.839129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:39.1311Z","time spent":"1.708019738s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T22:32:40.839381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.390005087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:32:40.83944Z","caller":"traceutil/trace.go:171","msg":"trace[1508590223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1342; }","duration":"1.390120019s","start":"2026-04-09T22:32:39.449306Z","end":"2026-04-09T22:32:40.839426Z","steps":["trace[1508590223] 'agreement among raft nodes before linearized reading' (duration: 1.390014657s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:40.839476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:39.449265Z","time spent":"1.390202931s","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T22:32:40.839478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.676627468s","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-04-09T22:32:40.839473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613621443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-09T22:32:40.839516Z","caller":"traceutil/trace.go:171","msg":"trace[1829456756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1342; }","duration":"1.676712001s","start":"2026-04-09T22:32:39.162794Z","end":"2026-04-09T22:32:40.839507Z","steps":["trace[1829456756] 'agreement among raft nodes before linearized reading' (duration: 1.676615338s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:40.839512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464785687s","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-04-09T22:32:40.839547Z","caller":"traceutil/trace.go:171","msg":"trace[1920992374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1342; }","duration":"1.613735207s","start":"2026-04-09T22:32:39.225794Z","end":"2026-04-09T22:32:40.839529Z","steps":["trace[1920992374] 'agreement among raft nodes before linearized reading' (duration: 1.613539251s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:40.839608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:39.225778Z","time spent":"1.613818538s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T22:32:40.83961Z","caller":"traceutil/trace.go:171","msg":"trace[518605300] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1342; }","duration":"1.464928761s","start":"2026-04-09T22:32:39.374657Z","end":"2026-04-09T22:32:40.839586Z","steps":["trace[518605300] 'agreement among raft nodes before linearized reading' (duration: 1.464636023s)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:40.839586Z","caller":"traceutil/trace.go:171","msg":"trace[1380299828] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"269.61408ms","start":"2026-04-09T22:32:40.569941Z","end":"2026-04-09T22:32:40.839555Z","steps":["trace[1380299828] 'process raft request' (duration: 268.734038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:40.839657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:39.374633Z","time spent":"1.465015774s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-09T22:32:40.839546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:39.162768Z","time spent":"1.676771623s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T22:32:40.839842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.231866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-09T22:32:40.839892Z","caller":"traceutil/trace.go:171","msg":"trace[1040908719] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1342; }","duration":"204.315728ms","start":"2026-04-09T22:32:40.635564Z","end":"2026-04-09T22:32:40.83988Z","steps":["trace[1040908719] 'agreement among raft nodes before linearized reading' (duration: 204.206476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:40.839911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.727788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:32:40.839922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686000386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-09T22:32:40.839971Z","caller":"traceutil/trace.go:171","msg":"trace[1089118732] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1342; }","duration":"353.857811ms","start":"2026-04-09T22:32:40.4861Z","end":"2026-04-09T22:32:40.839958Z","steps":["trace[1089118732] 'agreement among raft nodes before linearized reading' (duration: 353.153953ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:40.839965Z","caller":"traceutil/trace.go:171","msg":"trace[1490828939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"1.686065498s","start":"2026-04-09T22:32:39.153887Z","end":"2026-04-09T22:32:40.839953Z","steps":["trace[1490828939] 'agreement among raft nodes before linearized reading' (duration: 1.68534609s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:40.840018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:39.153843Z","time spent":"1.68616733s","remote":"127.0.0.1:58216","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-04-09T22:32:40.840021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:32:40.48607Z","time spent":"353.940203ms","remote":"127.0.0.1:58150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-04-09T22:32:49.37433Z","caller":"traceutil/trace.go:171","msg":"trace[1658922945] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"128.880182ms","start":"2026-04-09T22:32:49.24542Z","end":"2026-04-09T22:32:49.3743Z","steps":["trace[1658922945] 'process raft request' (duration: 128.589215ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:33:07.358431Z","caller":"traceutil/trace.go:171","msg":"trace[1516466453] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"103.790583ms","start":"2026-04-09T22:33:07.254618Z","end":"2026-04-09T22:33:07.358408Z","steps":["trace[1516466453] 'process raft request' (duration: 103.749212ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:33:07.358549Z","caller":"traceutil/trace.go:171","msg":"trace[1878777214] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"104.021199ms","start":"2026-04-09T22:33:07.254466Z","end":"2026-04-09T22:33:07.358487Z","steps":["trace[1878777214] 'process raft request' (duration: 78.777988ms)","trace[1878777214] 'compare' (duration: 25.018436ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:33:47.825605Z","caller":"traceutil/trace.go:171","msg":"trace[766293825] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"145.030017ms","start":"2026-04-09T22:33:47.680538Z","end":"2026-04-09T22:33:47.825568Z","steps":["trace[766293825] 'process raft request' (duration: 70.133731ms)","trace[766293825] 'compare' (duration: 74.738252ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:33:59.576074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.166225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:33:59.576163Z","caller":"traceutil/trace.go:171","msg":"trace[1529571982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1702; }","duration":"127.316918ms","start":"2026-04-09T22:33:59.448825Z","end":"2026-04-09T22:33:59.576142Z","steps":["trace[1529571982] 'range keys from in-memory index tree' (duration: 127.065822ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:08.050262Z","caller":"traceutil/trace.go:171","msg":"trace[443545844] linearizableReadLoop","detail":"{readStateIndex:1820; appliedIndex:1819; }","duration":"102.052336ms","start":"2026-04-09T22:34:07.948175Z","end":"2026-04-09T22:34:08.050227Z","steps":["trace[443545844] 'read index received' (duration: 100.740573ms)","trace[443545844] 'applied index is now lower than readState.Index' (duration: 1.310153ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:34:08.050393Z","caller":"traceutil/trace.go:171","msg":"trace[876836090] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"137.781542ms","start":"2026-04-09T22:34:07.912572Z","end":"2026-04-09T22:34:08.050353Z","steps":["trace[876836090] 'process raft request' (duration: 136.417968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:34:08.050882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.65263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:34:08.050958Z","caller":"traceutil/trace.go:171","msg":"trace[545184932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1745; }","duration":"102.797094ms","start":"2026-04-09T22:34:07.94814Z","end":"2026-04-09T22:34:08.050937Z","steps":["trace[545184932] 'agreement among raft nodes before linearized reading' (duration: 102.22969ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:16.299882Z","caller":"traceutil/trace.go:171","msg":"trace[1331859448] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"131.727344ms","start":"2026-04-09T22:34:16.168123Z","end":"2026-04-09T22:34:16.29985Z","steps":["trace[1331859448] 'process raft request' (duration: 94.49032ms)","trace[1331859448] 'compare' (duration: 36.602368ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:34:21.834655Z","caller":"traceutil/trace.go:171","msg":"trace[517918218] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"140.18877ms","start":"2026-04-09T22:34:21.694428Z","end":"2026-04-09T22:34:21.834617Z","steps":["trace[517918218] 'process raft request' (duration: 139.995625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:34:22.013867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.041493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-09T22:34:22.013965Z","caller":"traceutil/trace.go:171","msg":"trace[1318186052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1804; }","duration":"104.173726ms","start":"2026-04-09T22:34:21.909769Z","end":"2026-04-09T22:34:22.013943Z","steps":["trace[1318186052] 'range keys from in-memory index tree' (duration: 103.929361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:34:22.014259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.011132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-09T22:34:22.014342Z","caller":"traceutil/trace.go:171","msg":"trace[1501589456] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1804; }","duration":"111.149276ms","start":"2026-04-09T22:34:21.903169Z","end":"2026-04-09T22:34:22.014319Z","steps":["trace[1501589456] 'range keys from in-memory index tree' (duration: 110.850769ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:22.246185Z","caller":"traceutil/trace.go:171","msg":"trace[1199472906] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"225.5772ms","start":"2026-04-09T22:34:22.020574Z","end":"2026-04-09T22:34:22.246152Z","steps":["trace[1199472906] 'process raft request' (duration: 143.676535ms)","trace[1199472906] 'compare' (duration: 81.703191ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:34:22.246573Z","caller":"traceutil/trace.go:171","msg":"trace[1685235703] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"225.347706ms","start":"2026-04-09T22:34:22.021213Z","end":"2026-04-09T22:34:22.246561Z","steps":["trace[1685235703] 'process raft request' (duration: 224.892774ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:22.247155Z","caller":"traceutil/trace.go:171","msg":"trace[297052113] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1883; }","duration":"113.879483ms","start":"2026-04-09T22:34:22.133245Z","end":"2026-04-09T22:34:22.247125Z","steps":["trace[297052113] 'read index received' (duration: 31.033693ms)","trace[297052113] 'applied index is now lower than readState.Index' (duration: 82.84452ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:34:22.247374Z","caller":"traceutil/trace.go:171","msg":"trace[897888945] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"225.667473ms","start":"2026-04-09T22:34:22.02168Z","end":"2026-04-09T22:34:22.247348Z","steps":["trace[897888945] 'process raft request' (duration: 224.849822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:34:22.248156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.883246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T22:34:22.248263Z","caller":"traceutil/trace.go:171","msg":"trace[1594551053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1808; }","duration":"115.07674ms","start":"2026-04-09T22:34:22.133172Z","end":"2026-04-09T22:34:22.248248Z","steps":["trace[1594551053] 'agreement among raft nodes before linearized reading' (duration: 114.581089ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:22.471026Z","caller":"traceutil/trace.go:171","msg":"trace[1562937931] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"216.964262ms","start":"2026-04-09T22:34:22.254048Z","end":"2026-04-09T22:34:22.471012Z","steps":["trace[1562937931] 'process raft request' (duration: 216.738667ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:22.471018Z","caller":"traceutil/trace.go:171","msg":"trace[1303876253] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"217.441744ms","start":"2026-04-09T22:34:22.253541Z","end":"2026-04-09T22:34:22.470983Z","steps":["trace[1303876253] 'process raft request' (duration: 119.616081ms)","trace[1303876253] 'compare' (duration: 97.420632ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:35:08.311115Z","caller":"traceutil/trace.go:171","msg":"trace[1763033580] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"160.792395ms","start":"2026-04-09T22:35:08.150231Z","end":"2026-04-09T22:35:08.311023Z","steps":["trace[1763033580] 'process raft request' (duration: 160.652422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:35:08.572662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.879062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:35:08.572788Z","caller":"traceutil/trace.go:171","msg":"trace[415655303] linearizableReadLoop","detail":"{readStateIndex:2106; appliedIndex:2105; }","duration":"123.51729ms","start":"2026-04-09T22:35:08.449251Z","end":"2026-04-09T22:35:08.572768Z","steps":["trace[415655303] 'read index received' (duration: 73.922µs)","trace[415655303] 'applied index is now lower than readState.Index' (duration: 123.441908ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:35:08.572824Z","caller":"traceutil/trace.go:171","msg":"trace[418176627] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"211.978328ms","start":"2026-04-09T22:35:08.3608Z","end":"2026-04-09T22:35:08.572779Z","steps":["trace[418176627] 'process raft request' (duration: 44.87923ms)","trace[418176627] 'compare' (duration: 166.744479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:35:08.573041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.768136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:35:08.573153Z","caller":"traceutil/trace.go:171","msg":"trace[1757169124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2019; }","duration":"123.93159ms","start":"2026-04-09T22:35:08.449198Z","end":"2026-04-09T22:35:08.57313Z","steps":["trace[1757169124] 'agreement among raft nodes before linearized reading' (duration: 123.711114ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:35:08.751322Z","caller":"traceutil/trace.go:171","msg":"trace[621398311] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"172.11042ms","start":"2026-04-09T22:35:08.57918Z","end":"2026-04-09T22:35:08.75129Z","steps":["trace[621398311] 'process raft request' (duration: 97.939609ms)","trace[621398311] 'compare' (duration: 73.71003ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:35:08.960425Z","caller":"traceutil/trace.go:171","msg":"trace[1918303272] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"202.95494ms","start":"2026-04-09T22:35:08.757422Z","end":"2026-04-09T22:35:08.960377Z","steps":["trace[1918303272] 'process raft request' (duration: 191.630295ms)","trace[1918303272] 'compare' (duration: 10.779732ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:35:55.852081Z","caller":"traceutil/trace.go:171","msg":"trace[287812493] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"179.947036ms","start":"2026-04-09T22:35:55.672101Z","end":"2026-04-09T22:35:55.852048Z","steps":["trace[287812493] 'process raft request' (duration: 179.777892ms)"],"step_count":1}