{"level":"warn","ts":"2026-05-06T00:42:53.970378Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T00:42:53.970478Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.65:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.65:2380","--initial-cluster=instance=https://199.19.213.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.65:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-06T00:42:53.970552Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T00:42:53.970563Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.65:2380"]} {"level":"info","ts":"2026-05-06T00:42:53.97058Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-06T00:42:53.971207Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"]} {"level":"info","ts":"2026-05-06T00:42:53.971279Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.65:2380"],"listen-peer-urls":["https://199.19.213.65:2380"],"advertise-client-urls":["https://199.19.213.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.65:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-06T00:42:54.044874Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"72.925459ms"} {"level":"info","ts":"2026-05-06T00:42:54.132518Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a071a454a8148542","cluster-id":"aa233e43aeaf7fe5"} {"level":"info","ts":"2026-05-06T00:42:54.132707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T00:42:54.132757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became follower at term 0"} {"level":"info","ts":"2026-05-06T00:42:54.132781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a071a454a8148542 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T00:42:54.132795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became follower at term 1"} {"level":"info","ts":"2026-05-06T00:42:54.13287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=(11561202401940899138)"} {"level":"warn","ts":"2026-05-06T00:42:54.170939Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T00:42:54.188883Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T00:42:54.199098Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-06T00:42:54.213867Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a071a454a8148542","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T00:42:54.214122Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-06T00:42:54.214255Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-06T00:42:54.214199Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a071a454a8148542","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T00:42:54.214281Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-06T00:42:54.221464Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-06T00:42:54.22161Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.65:2380"} {"level":"info","ts":"2026-05-06T00:42:54.22168Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.65:2380"} {"level":"info","ts":"2026-05-06T00:42:54.221708Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a071a454a8148542","initial-advertise-peer-urls":["https://199.19.213.65:2380"],"listen-peer-urls":["https://199.19.213.65:2380"],"advertise-client-urls":["https://199.19.213.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T00:42:54.221768Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T00:42:54.270429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=(11561202401940899138)"} {"level":"info","ts":"2026-05-06T00:42:54.270549Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa233e43aeaf7fe5","local-member-id":"a071a454a8148542","added-peer-id":"a071a454a8148542","added-peer-peer-urls":["https://199.19.213.65:2380"]} {"level":"info","ts":"2026-05-06T00:42:54.934222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T00:42:54.934293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T00:42:54.93433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 received MsgPreVoteResp from a071a454a8148542 at term 1"} {"level":"info","ts":"2026-05-06T00:42:54.934347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became candidate at term 2"} {"level":"info","ts":"2026-05-06T00:42:54.934352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 received MsgVoteResp from a071a454a8148542 at term 2"} {"level":"info","ts":"2026-05-06T00:42:54.934362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became leader at term 2"} {"level":"info","ts":"2026-05-06T00:42:54.93437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a071a454a8148542 elected leader a071a454a8148542 at term 2"} {"level":"info","ts":"2026-05-06T00:42:54.94234Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a071a454a8148542","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.65:2379]}","request-path":"/0/members/a071a454a8148542/attributes","cluster-id":"aa233e43aeaf7fe5","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T00:42:54.942413Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:42:54.942573Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:42:54.942581Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:42:54.944146Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa233e43aeaf7fe5","local-member-id":"a071a454a8148542","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:42:54.944359Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T00:42:54.944386Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:42:54.944403Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T00:42:54.944431Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:42:54.945523Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T00:42:54.950183Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.65:2379"} {"level":"warn","ts":"2026-05-06T00:43:04.187639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.011776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:04.187732Z","caller":"traceutil/trace.go:171","msg":"trace[2026957952] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:310; }","duration":"127.121287ms","start":"2026-05-06T00:43:04.060594Z","end":"2026-05-06T00:43:04.187715Z","steps":["trace[2026957952] 'range keys from in-memory index tree' (duration: 126.945256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:04.423374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.259803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:04.42346Z","caller":"traceutil/trace.go:171","msg":"trace[1351098343] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:311; }","duration":"113.406225ms","start":"2026-05-06T00:43:04.310034Z","end":"2026-05-06T00:43:04.423441Z","steps":["trace[1351098343] 'range keys from in-memory index tree' (duration: 113.144762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:04.423454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.037248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:43:04.423553Z","caller":"traceutil/trace.go:171","msg":"trace[1145695317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:311; }","duration":"124.190859ms","start":"2026-05-06T00:43:04.299343Z","end":"2026-05-06T00:43:04.423534Z","steps":["trace[1145695317] 'range keys from in-memory index tree' (duration: 123.921537ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:04.552573Z","caller":"traceutil/trace.go:171","msg":"trace[1696043226] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"124.747344ms","start":"2026-05-06T00:43:04.427805Z","end":"2026-05-06T00:43:04.552553Z","steps":["trace[1696043226] 'process raft request' (duration: 124.616123ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:04.572359Z","caller":"traceutil/trace.go:171","msg":"trace[885870918] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"142.146242ms","start":"2026-05-06T00:43:04.43019Z","end":"2026-05-06T00:43:04.572336Z","steps":["trace[885870918] 'process raft request' (duration: 142.016461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.496161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.17245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:43:05.496266Z","caller":"traceutil/trace.go:171","msg":"trace[1788816607] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:324; }","duration":"875.441167ms","start":"2026-05-06T00:43:04.620809Z","end":"2026-05-06T00:43:05.49625Z","steps":["trace[1788816607] 'read index received' (duration: 330.549809ms)","trace[1788816607] 'applied index is now lower than readState.Index' (duration: 544.889978ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:05.496303Z","caller":"traceutil/trace.go:171","msg":"trace[305138427] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"878.79558ms","start":"2026-05-06T00:43:04.617469Z","end":"2026-05-06T00:43:05.496265Z","steps":["trace[305138427] 'process raft request' (duration: 333.948972ms)","trace[305138427] 'compare' (duration: 544.060599ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:05.49638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.690933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-06T00:43:05.496411Z","caller":"traceutil/trace.go:171","msg":"trace[336428573] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:314; }","duration":"867.760464ms","start":"2026-05-06T00:43:04.628642Z","end":"2026-05-06T00:43:05.496402Z","steps":["trace[336428573] 'agreement among raft nodes before linearized reading' (duration: 867.700173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.496427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.429644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-06T00:43:05.496474Z","caller":"traceutil/trace.go:171","msg":"trace[1081865021] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:314; }","duration":"685.556175ms","start":"2026-05-06T00:43:04.810899Z","end":"2026-05-06T00:43:05.496455Z","steps":["trace[1081865021] 'agreement among raft nodes before linearized reading' (duration: 685.459234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.496446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.628628Z","time spent":"867.809904ms","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-05-06T00:43:05.49651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.810864Z","time spent":"685.635426ms","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-05-06T00:43:05.496443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.62178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:43:05.4966Z","caller":"traceutil/trace.go:171","msg":"trace[1881672974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:314; }","duration":"875.783711ms","start":"2026-05-06T00:43:04.620758Z","end":"2026-05-06T00:43:05.496542Z","steps":["trace[1881672974] 'agreement among raft nodes before linearized reading' (duration: 875.559359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.49668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.428128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-05-06T00:43:05.496702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.527822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-05-06T00:43:05.49669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.617443Z","time spent":"878.990152ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:43:05.496734Z","caller":"traceutil/trace.go:171","msg":"trace[955184392] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:314; }","duration":"273.597512ms","start":"2026-05-06T00:43:05.223129Z","end":"2026-05-06T00:43:05.496726Z","steps":["trace[955184392] 'agreement among raft nodes before linearized reading' (duration: 273.543991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.4967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.620739Z","time spent":"875.948522ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T00:43:05.496766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.784639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T00:43:05.496797Z","caller":"traceutil/trace.go:171","msg":"trace[1598434946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:314; }","duration":"821.84063ms","start":"2026-05-06T00:43:04.67495Z","end":"2026-05-06T00:43:05.49679Z","steps":["trace[1598434946] 'agreement among raft nodes before linearized reading' (duration: 821.7932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.496826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.674934Z","time spent":"821.885751ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T00:43:05.496862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.687993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-05-06T00:43:05.496887Z","caller":"traceutil/trace.go:171","msg":"trace[507483524] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:314; }","duration":"273.749953ms","start":"2026-05-06T00:43:05.223129Z","end":"2026-05-06T00:43:05.496879Z","steps":["trace[507483524] 'agreement among raft nodes before linearized reading' (duration: 273.704423ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:05.496722Z","caller":"traceutil/trace.go:171","msg":"trace[55657423] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:314; }","duration":"786.49916ms","start":"2026-05-06T00:43:04.710212Z","end":"2026-05-06T00:43:05.496712Z","steps":["trace[55657423] 'agreement among raft nodes before linearized reading' (duration: 786.443299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.496878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.329066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-05-06T00:43:05.496919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.328782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-06T00:43:05.496939Z","caller":"traceutil/trace.go:171","msg":"trace[436179644] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:314; }","duration":"847.408658ms","start":"2026-05-06T00:43:04.649513Z","end":"2026-05-06T00:43:05.496922Z","steps":["trace[436179644] 'agreement among raft nodes before linearized reading' (duration: 847.300607ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:05.496951Z","caller":"traceutil/trace.go:171","msg":"trace[1542470911] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:314; }","duration":"859.390383ms","start":"2026-05-06T00:43:04.637553Z","end":"2026-05-06T00:43:05.496943Z","steps":["trace[1542470911] 'agreement among raft nodes before linearized reading' (duration: 859.341932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.496977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.569448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-05-06T00:43:05.496978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.637536Z","time spent":"859.436803ms","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-06T00:43:05.496985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.649504Z","time spent":"847.470568ms","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-05-06T00:43:05.497004Z","caller":"traceutil/trace.go:171","msg":"trace[501896890] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:314; }","duration":"536.644948ms","start":"2026-05-06T00:43:04.960353Z","end":"2026-05-06T00:43:05.496998Z","steps":["trace[501896890] 'agreement among raft nodes before linearized reading' (duration: 536.588918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.497023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.960334Z","time spent":"536.682739ms","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-05-06T00:43:05.496638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.497418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-06T00:43:05.497141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.159216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-06T00:43:05.497104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.464473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-05-06T00:43:05.497153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.746974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-06T00:43:05.497197Z","caller":"traceutil/trace.go:171","msg":"trace[315044596] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:314; }","duration":"587.240196ms","start":"2026-05-06T00:43:04.909943Z","end":"2026-05-06T00:43:05.497183Z","steps":["trace[315044596] 'agreement among raft nodes before linearized reading' (duration: 587.154165ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:05.49725Z","caller":"traceutil/trace.go:171","msg":"trace[736442957] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:314; }","duration":"831.618844ms","start":"2026-05-06T00:43:04.665582Z","end":"2026-05-06T00:43:05.497201Z","steps":["trace[736442957] 'agreement among raft nodes before linearized reading' (duration: 831.363412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.497269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.909929Z","time spent":"587.328548ms","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-05-06T00:43:05.497269Z","caller":"traceutil/trace.go:171","msg":"trace[715979126] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:314; }","duration":"635.943237ms","start":"2026-05-06T00:43:04.861299Z","end":"2026-05-06T00:43:05.497242Z","steps":["trace[715979126] 'agreement among raft nodes before linearized reading' (duration: 635.672894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.497283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.074261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-05-06T00:43:05.49731Z","caller":"traceutil/trace.go:171","msg":"trace[1940020723] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:314; }","duration":"595.131622ms","start":"2026-05-06T00:43:04.902172Z","end":"2026-05-06T00:43:05.497304Z","steps":["trace[1940020723] 'agreement among raft nodes before linearized reading' (duration: 595.076172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.497323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.86127Z","time spent":"636.045867ms","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-05-06T00:43:05.49734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.902154Z","time spent":"595.179972ms","remote":"127.0.0.1:37600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6985,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-05-06T00:43:05.49719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.710195Z","time spent":"786.720152ms","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-05-06T00:43:05.497499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.665563Z","time spent":"831.720885ms","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-05-06T00:43:05.497135Z","caller":"traceutil/trace.go:171","msg":"trace[1136943059] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:314; }","duration":"735.980292ms","start":"2026-05-06T00:43:04.761134Z","end":"2026-05-06T00:43:05.497114Z","steps":["trace[1136943059] 'agreement among raft nodes before linearized reading' (duration: 735.465108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.497666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.703287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-06T00:43:05.497735Z","caller":"traceutil/trace.go:171","msg":"trace[1751020600] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:314; }","duration":"852.783798ms","start":"2026-05-06T00:43:04.644927Z","end":"2026-05-06T00:43:05.497711Z","steps":["trace[1751020600] 'agreement among raft nodes before linearized reading' (duration: 851.94707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:05.497776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.644918Z","time spent":"852.846708ms","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-05-06T00:43:05.497672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:04.761088Z","time spent":"736.566347ms","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-05-06T00:43:06.010922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411055743186928,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:43:06.511421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411055743186928,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:43:07.012163Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411055743186928,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:43:07.270553Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.767161356s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:43:07.27176Z","caller":"traceutil/trace.go:171","msg":"trace[151332261] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"1.768467418s","start":"2026-05-06T00:43:05.503259Z","end":"2026-05-06T00:43:07.271726Z","steps":["trace[151332261] 'process raft request' (duration: 1.768257335s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.271996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.503245Z","time spent":"1.76862026s","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:43:07.535584Z","caller":"traceutil/trace.go:171","msg":"trace[984808975] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"2.029232065s","start":"2026-05-06T00:43:05.506329Z","end":"2026-05-06T00:43:07.535561Z","steps":["trace[984808975] 'process raft request' (duration: 2.028977603s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.535736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.506318Z","time spent":"2.029336416s","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:43:07.535786Z","caller":"traceutil/trace.go:171","msg":"trace[1258998575] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"2.029108284s","start":"2026-05-06T00:43:05.506662Z","end":"2026-05-06T00:43:07.53577Z","steps":["trace[1258998575] 'process raft request' (duration: 2.028784001s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.535894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.506615Z","time spent":"2.029214525s","remote":"127.0.0.1:37600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:43:07.536329Z","caller":"traceutil/trace.go:171","msg":"trace[339628862] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"2.022683582s","start":"2026-05-06T00:43:05.513617Z","end":"2026-05-06T00:43:07.536301Z","steps":["trace[339628862] 'process raft request' (duration: 2.022573461s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:07.53636Z","caller":"traceutil/trace.go:171","msg":"trace[107192822] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"2.022820983s","start":"2026-05-06T00:43:05.513525Z","end":"2026-05-06T00:43:07.536346Z","steps":["trace[107192822] 'process raft request' (duration: 2.022167958s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.536435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.513511Z","time spent":"2.022895554s","remote":"127.0.0.1:37502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:43:07.536467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.513593Z","time spent":"2.022811233s","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:43:07.536633Z","caller":"traceutil/trace.go:171","msg":"trace[2098461787] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:325; }","duration":"2.026243296s","start":"2026-05-06T00:43:05.510377Z","end":"2026-05-06T00:43:07.53662Z","steps":["trace[2098461787] 'read index received' (duration: 1.760837555s)","trace[2098461787] 'applied index is now lower than readState.Index' (duration: 265.404811ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:07.536677Z","caller":"traceutil/trace.go:171","msg":"trace[445529727] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"2.022754742s","start":"2026-05-06T00:43:05.513911Z","end":"2026-05-06T00:43:07.536666Z","steps":["trace[445529727] 'process raft request' (duration: 2.02251113s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:07.536714Z","caller":"traceutil/trace.go:171","msg":"trace[1446470319] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"2.022634192s","start":"2026-05-06T00:43:05.514071Z","end":"2026-05-06T00:43:07.536705Z","steps":["trace[1446470319] 'process raft request' (duration: 2.022377919s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.536723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.513895Z","time spent":"2.022805653s","remote":"127.0.0.1:37520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:43:07.536756Z","caller":"traceutil/trace.go:171","msg":"trace[655751753] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"2.022971925s","start":"2026-05-06T00:43:05.513772Z","end":"2026-05-06T00:43:07.536744Z","steps":["trace[655751753] 'process raft request' (duration: 2.022550541s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.536769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.514064Z","time spent":"2.022669532s","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:43:07.53683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.513758Z","time spent":"2.023028505s","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:43:07.536913Z","caller":"traceutil/trace.go:171","msg":"trace[1138186469] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"2.023121445s","start":"2026-05-06T00:43:05.51378Z","end":"2026-05-06T00:43:07.536901Z","steps":["trace[1138186469] 'process raft request' (duration: 2.022574271s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.536962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.513774Z","time spent":"2.023165437s","remote":"127.0.0.1:37934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:43:07.536981Z","caller":"traceutil/trace.go:171","msg":"trace[1683900419] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"2.023327318s","start":"2026-05-06T00:43:05.513644Z","end":"2026-05-06T00:43:07.536972Z","steps":["trace[1683900419] 'process raft request' (duration: 2.022589591s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:07.537018Z","caller":"traceutil/trace.go:171","msg":"trace[1515131096] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"2.022834733s","start":"2026-05-06T00:43:05.514173Z","end":"2026-05-06T00:43:07.537008Z","steps":["trace[1515131096] 'process raft request' (duration: 2.022306119s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.538202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.514165Z","time spent":"2.024007615s","remote":"127.0.0.1:37920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:43:07.537024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.513633Z","time spent":"2.023370468s","remote":"127.0.0.1:37590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:43:07.537251Z","caller":"traceutil/trace.go:171","msg":"trace[1497413904] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"2.023341937s","start":"2026-05-06T00:43:05.513899Z","end":"2026-05-06T00:43:07.537241Z","steps":["trace[1497413904] 'process raft request' (duration: 2.02248403s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.538525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.513893Z","time spent":"2.02459921s","remote":"127.0.0.1:37520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:43:07.537269Z","caller":"traceutil/trace.go:171","msg":"trace[159964087] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"2.02354491s","start":"2026-05-06T00:43:05.513715Z","end":"2026-05-06T00:43:07.53726Z","steps":["trace[159964087] 'process raft request' (duration: 2.022555991s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:07.537469Z","caller":"traceutil/trace.go:171","msg":"trace[2138605146] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"436.343807ms","start":"2026-05-06T00:43:07.101115Z","end":"2026-05-06T00:43:07.537459Z","steps":["trace[2138605146] 'process raft request' (duration: 435.46874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.539082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:07.101062Z","time spent":"437.965254ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:43:07.53751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.027128914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-06T00:43:07.539075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.513709Z","time spent":"2.025216836s","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:43:07.539146Z","caller":"traceutil/trace.go:171","msg":"trace[327345713] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:330; }","duration":"2.02877759s","start":"2026-05-06T00:43:05.510356Z","end":"2026-05-06T00:43:07.539134Z","steps":["trace[327345713] 'agreement among raft nodes before linearized reading' (duration: 2.027114224s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.53918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.510347Z","time spent":"2.0288257s","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-05-06T00:43:07.537554Z","caller":"traceutil/trace.go:171","msg":"trace[14938910] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"2.022927224s","start":"2026-05-06T00:43:05.514619Z","end":"2026-05-06T00:43:07.537546Z","steps":["trace[14938910] 'process raft request' (duration: 2.021898455s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.539275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.514612Z","time spent":"2.024649511s","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:43:07.537565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.767229072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:07.53936Z","caller":"traceutil/trace.go:171","msg":"trace[315292005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:330; }","duration":"1.769056551s","start":"2026-05-06T00:43:05.770297Z","end":"2026-05-06T00:43:07.539353Z","steps":["trace[315292005] 'agreement among raft nodes before linearized reading' (duration: 1.767251483s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.539381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.770276Z","time spent":"1.769099451s","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:43:07.537667Z","caller":"traceutil/trace.go:171","msg":"trace[756425339] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"2.022039265s","start":"2026-05-06T00:43:05.51562Z","end":"2026-05-06T00:43:07.537659Z","steps":["trace[756425339] 'process raft request' (duration: 2.020930165s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.5395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:05.515615Z","time spent":"2.023864613s","remote":"127.0.0.1:37594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:43:07.537678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.747911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:43:07.539606Z","caller":"traceutil/trace.go:171","msg":"trace[1410304516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:330; }","duration":"982.71585ms","start":"2026-05-06T00:43:06.556883Z","end":"2026-05-06T00:43:07.539599Z","steps":["trace[1410304516] 'agreement among raft nodes before linearized reading' (duration: 980.768881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:07.53963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:06.55685Z","time spent":"982.77305ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T00:43:09.723153Z","caller":"traceutil/trace.go:171","msg":"trace[370365109] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"116.77303ms","start":"2026-05-06T00:43:09.606356Z","end":"2026-05-06T00:43:09.723129Z","steps":["trace[370365109] 'process raft request' (duration: 116.193714ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:09.728809Z","caller":"traceutil/trace.go:171","msg":"trace[1722709158] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"122.10852ms","start":"2026-05-06T00:43:09.606676Z","end":"2026-05-06T00:43:09.728784Z","steps":["trace[1722709158] 'process raft request' (duration: 121.976348ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:17.873021Z","caller":"traceutil/trace.go:171","msg":"trace[881148337] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"108.335744ms","start":"2026-05-06T00:43:17.764662Z","end":"2026-05-06T00:43:17.872997Z","steps":["trace[881148337] 'read index received' (duration: 108.197043ms)","trace[881148337] 'applied index is now lower than readState.Index' (duration: 137.561µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:17.873195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.516255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T00:43:17.873242Z","caller":"traceutil/trace.go:171","msg":"trace[1705803540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:428; }","duration":"108.603387ms","start":"2026-05-06T00:43:17.764626Z","end":"2026-05-06T00:43:17.873229Z","steps":["trace[1705803540] 'agreement among raft nodes before linearized reading' (duration: 108.479196ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:17.873584Z","caller":"traceutil/trace.go:171","msg":"trace[784831804] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"110.144599ms","start":"2026-05-06T00:43:17.763428Z","end":"2026-05-06T00:43:17.873573Z","steps":["trace[784831804] 'process raft request' (duration: 109.409083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:27.850737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.281561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:43:27.850854Z","caller":"traceutil/trace.go:171","msg":"trace[1455692548] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:473; }","duration":"145.544364ms","start":"2026-05-06T00:43:27.705292Z","end":"2026-05-06T00:43:27.850836Z","steps":["trace[1455692548] 'read index received' (duration: 33.036862ms)","trace[1455692548] 'applied index is now lower than readState.Index' (duration: 112.506362ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:27.851057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.755686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:27.851095Z","caller":"traceutil/trace.go:171","msg":"trace[236764479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:456; }","duration":"145.798706ms","start":"2026-05-06T00:43:27.705287Z","end":"2026-05-06T00:43:27.851086Z","steps":["trace[236764479] 'agreement among raft nodes before linearized reading' (duration: 145.711406ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:27.851345Z","caller":"traceutil/trace.go:171","msg":"trace[1259634857] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"155.541924ms","start":"2026-05-06T00:43:27.695764Z","end":"2026-05-06T00:43:27.851306Z","steps":["trace[1259634857] 'process raft request' (duration: 42.602248ms)","trace[1259634857] 'compare' (duration: 112.16211ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:28.334162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.824695ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-06T00:43:29.742958Z","caller":"traceutil/trace.go:171","msg":"trace[1290843877] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"124.447404ms","start":"2026-05-06T00:43:29.618484Z","end":"2026-05-06T00:43:29.742931Z","steps":["trace[1290843877] 'process raft request' (duration: 124.250393ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:29.743148Z","caller":"traceutil/trace.go:171","msg":"trace[1474635479] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"122.174667ms","start":"2026-05-06T00:43:29.620964Z","end":"2026-05-06T00:43:29.743139Z","steps":["trace[1474635479] 'process raft request' (duration: 121.932125ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:29.743236Z","caller":"traceutil/trace.go:171","msg":"trace[950536728] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"123.854439ms","start":"2026-05-06T00:43:29.619374Z","end":"2026-05-06T00:43:29.743228Z","steps":["trace[950536728] 'process raft request' (duration: 123.477156ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:29.743317Z","caller":"traceutil/trace.go:171","msg":"trace[677283178] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"114.732228ms","start":"2026-05-06T00:43:29.628578Z","end":"2026-05-06T00:43:29.74331Z","steps":["trace[677283178] 'process raft request' (duration: 114.410925ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:29.743534Z","caller":"traceutil/trace.go:171","msg":"trace[6050712] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"116.849995ms","start":"2026-05-06T00:43:29.626675Z","end":"2026-05-06T00:43:29.743525Z","steps":["trace[6050712] 'process raft request' (duration: 116.26966ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:29.743578Z","caller":"traceutil/trace.go:171","msg":"trace[827888694] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"110.901308ms","start":"2026-05-06T00:43:29.632648Z","end":"2026-05-06T00:43:29.743549Z","steps":["trace[827888694] 'process raft request' (duration: 110.503765ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:29.743738Z","caller":"traceutil/trace.go:171","msg":"trace[1432345327] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"112.786033ms","start":"2026-05-06T00:43:29.630942Z","end":"2026-05-06T00:43:29.743728Z","steps":["trace[1432345327] 'process raft request' (duration: 112.166988ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:29.743805Z","caller":"traceutil/trace.go:171","msg":"trace[202316630] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"101.179133ms","start":"2026-05-06T00:43:29.642616Z","end":"2026-05-06T00:43:29.743795Z","steps":["trace[202316630] 'process raft request' (duration: 100.615138ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:29.836736Z","caller":"traceutil/trace.go:171","msg":"trace[919619891] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"155.101755ms","start":"2026-05-06T00:43:29.681598Z","end":"2026-05-06T00:43:29.8367Z","steps":["trace[919619891] 'process raft request' (duration: 119.192964ms)","trace[919619891] 'compare' (duration: 35.155215ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:29.837034Z","caller":"traceutil/trace.go:171","msg":"trace[1358673690] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"147.458265ms","start":"2026-05-06T00:43:29.689554Z","end":"2026-05-06T00:43:29.837012Z","steps":["trace[1358673690] 'process raft request' (duration: 147.138173ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:29.837124Z","caller":"traceutil/trace.go:171","msg":"trace[315864509] transaction","detail":"{read_only:false; number_of_response:1; response_revision:501; }","duration":"147.522016ms","start":"2026-05-06T00:43:29.689572Z","end":"2026-05-06T00:43:29.837094Z","steps":["trace[315864509] 'process raft request' (duration: 147.157143ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:29.837161Z","caller":"traceutil/trace.go:171","msg":"trace[1158079173] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:509; }","duration":"154.326148ms","start":"2026-05-06T00:43:29.68281Z","end":"2026-05-06T00:43:29.837136Z","steps":["trace[1158079173] 'read index received' (duration: 60.05511ms)","trace[1158079173] 'applied index is now lower than readState.Index' (duration: 94.257678ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:29.837351Z","caller":"traceutil/trace.go:171","msg":"trace[1933548571] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"148.08784ms","start":"2026-05-06T00:43:29.689252Z","end":"2026-05-06T00:43:29.837339Z","steps":["trace[1933548571] 'process raft request' (duration: 147.003491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:29.837523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.671461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:43:29.837621Z","caller":"traceutil/trace.go:171","msg":"trace[1872043572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:501; }","duration":"154.830792ms","start":"2026-05-06T00:43:29.682779Z","end":"2026-05-06T00:43:29.837609Z","steps":["trace[1872043572] 'agreement among raft nodes before linearized reading' (duration: 154.56696ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:29.851638Z","caller":"traceutil/trace.go:171","msg":"trace[1616353644] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"101.681126ms","start":"2026-05-06T00:43:29.749948Z","end":"2026-05-06T00:43:29.851629Z","steps":["trace[1616353644] 'process raft request' (duration: 101.534944ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:30.030172Z","caller":"traceutil/trace.go:171","msg":"trace[417716890] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"131.286257ms","start":"2026-05-06T00:43:29.89884Z","end":"2026-05-06T00:43:30.030126Z","steps":["trace[417716890] 'process raft request' (duration: 48.887352ms)","trace[417716890] 'compare' (duration: 82.100533ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:30.030839Z","caller":"traceutil/trace.go:171","msg":"trace[1275448883] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"128.818708ms","start":"2026-05-06T00:43:29.902005Z","end":"2026-05-06T00:43:30.030824Z","steps":["trace[1275448883] 'process raft request' (duration: 128.640027ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:30.03082Z","caller":"traceutil/trace.go:171","msg":"trace[358680759] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"131.836592ms","start":"2026-05-06T00:43:29.898933Z","end":"2026-05-06T00:43:30.030769Z","steps":["trace[358680759] 'process raft request' (duration: 131.289498ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:30.030994Z","caller":"traceutil/trace.go:171","msg":"trace[1410918967] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"107.5465ms","start":"2026-05-06T00:43:29.923441Z","end":"2026-05-06T00:43:30.030987Z","steps":["trace[1410918967] 'process raft request' (duration: 107.313459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:30.049605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.793511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:43:30.049678Z","caller":"traceutil/trace.go:171","msg":"trace[766086890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"103.910332ms","start":"2026-05-06T00:43:29.945751Z","end":"2026-05-06T00:43:30.049662Z","steps":["trace[766086890] 'agreement among raft nodes before linearized reading' (duration: 103.72281ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:30.361612Z","caller":"traceutil/trace.go:171","msg":"trace[671710617] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"151.309958ms","start":"2026-05-06T00:43:30.21027Z","end":"2026-05-06T00:43:30.36158Z","steps":["trace[671710617] 'process raft request' (duration: 95.972787ms)","trace[671710617] 'compare' (duration: 55.18161ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:30.371794Z","caller":"traceutil/trace.go:171","msg":"trace[1770233715] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"150.29869ms","start":"2026-05-06T00:43:30.221462Z","end":"2026-05-06T00:43:30.371761Z","steps":["trace[1770233715] 'process raft request' (duration: 150.1705ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:30.372047Z","caller":"traceutil/trace.go:171","msg":"trace[864866123] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"161.098545ms","start":"2026-05-06T00:43:30.210939Z","end":"2026-05-06T00:43:30.372037Z","steps":["trace[864866123] 'process raft request' (duration: 160.472849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:30.585497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.239948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:43:30.585717Z","caller":"traceutil/trace.go:171","msg":"trace[22863645] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"125.487516ms","start":"2026-05-06T00:43:30.460217Z","end":"2026-05-06T00:43:30.585705Z","steps":["trace[22863645] 'process raft request' (duration: 125.454826ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:30.585819Z","caller":"traceutil/trace.go:171","msg":"trace[2060973354] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"146.591681ms","start":"2026-05-06T00:43:30.43922Z","end":"2026-05-06T00:43:30.585812Z","steps":["trace[2060973354] 'process raft request' (duration: 26.97975ms)","trace[2060973354] 'compare' (duration: 119.097167ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:30.585949Z","caller":"traceutil/trace.go:171","msg":"trace[1500275996] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"142.726141ms","start":"2026-05-06T00:43:30.443217Z","end":"2026-05-06T00:43:30.585943Z","steps":["trace[1500275996] 'process raft request' (duration: 142.385269ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:30.727686Z","caller":"traceutil/trace.go:171","msg":"trace[323349543] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"113.978428ms","start":"2026-05-06T00:43:30.613674Z","end":"2026-05-06T00:43:30.727653Z","steps":["trace[323349543] 'process raft request' (duration: 87.745113ms)","trace[323349543] 'compare' (duration: 26.056203ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:30.729675Z","caller":"traceutil/trace.go:171","msg":"trace[1905345842] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"113.980797ms","start":"2026-05-06T00:43:30.61568Z","end":"2026-05-06T00:43:30.72966Z","steps":["trace[1905345842] 'process raft request' (duration: 113.652544ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:31.268425Z","caller":"traceutil/trace.go:171","msg":"trace[957059034] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"107.09135ms","start":"2026-05-06T00:43:31.161305Z","end":"2026-05-06T00:43:31.268396Z","steps":["trace[957059034] 'process raft request' (duration: 69.964872ms)","trace[957059034] 'compare' (duration: 36.967637ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:31.85308Z","caller":"traceutil/trace.go:171","msg":"trace[611136180] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"238.9842ms","start":"2026-05-06T00:43:31.614046Z","end":"2026-05-06T00:43:31.85303Z","steps":["trace[611136180] 'read index received' (duration: 183.700503ms)","trace[611136180] 'applied index is now lower than readState.Index' (duration: 55.282907ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:31.853301Z","caller":"traceutil/trace.go:171","msg":"trace[1405623919] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"247.585675ms","start":"2026-05-06T00:43:31.605672Z","end":"2026-05-06T00:43:31.853258Z","steps":["trace[1405623919] 'process raft request' (duration: 192.016856ms)","trace[1405623919] 'compare' (duration: 55.012445ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:31.853413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.342372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-05-06T00:43:31.853451Z","caller":"traceutil/trace.go:171","msg":"trace[298489637] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:567; }","duration":"239.426064ms","start":"2026-05-06T00:43:31.614015Z","end":"2026-05-06T00:43:31.853441Z","steps":["trace[298489637] 'agreement among raft nodes before linearized reading' (duration: 239.130551ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:31.854546Z","caller":"traceutil/trace.go:171","msg":"trace[245349102] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"231.324611ms","start":"2026-05-06T00:43:31.623195Z","end":"2026-05-06T00:43:31.85452Z","steps":["trace[245349102] 'process raft request' (duration: 231.117529ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:32.048061Z","caller":"traceutil/trace.go:171","msg":"trace[908001329] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"119.650854ms","start":"2026-05-06T00:43:31.928389Z","end":"2026-05-06T00:43:32.048039Z","steps":["trace[908001329] 'read index received' (duration: 91.251476ms)","trace[908001329] 'applied index is now lower than readState.Index' (duration: 28.398478ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:32.048164Z","caller":"traceutil/trace.go:171","msg":"trace[417559534] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"147.085736ms","start":"2026-05-06T00:43:31.901048Z","end":"2026-05-06T00:43:32.048134Z","steps":["trace[417559534] 'process raft request' (duration: 118.679828ms)","trace[417559534] 'compare' (duration: 28.179016ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:32.048467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.020267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-05-06T00:43:32.048684Z","caller":"traceutil/trace.go:171","msg":"trace[1074315133] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:570; }","duration":"120.203348ms","start":"2026-05-06T00:43:31.928335Z","end":"2026-05-06T00:43:32.048538Z","steps":["trace[1074315133] 'agreement among raft nodes before linearized reading' (duration: 119.813685ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:32.331183Z","caller":"traceutil/trace.go:171","msg":"trace[92862142] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"218.662323ms","start":"2026-05-06T00:43:32.112489Z","end":"2026-05-06T00:43:32.331152Z","steps":["trace[92862142] 'process raft request' (duration: 130.982479ms)","trace[92862142] 'compare' (duration: 87.258261ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:32.331538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.331654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:29476"} {"level":"info","ts":"2026-05-06T00:43:32.331642Z","caller":"traceutil/trace.go:171","msg":"trace[1397365582] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:573; }","duration":"212.474325ms","start":"2026-05-06T00:43:32.119149Z","end":"2026-05-06T00:43:32.331623Z","steps":["trace[1397365582] 'agreement among raft nodes before linearized reading' (duration: 212.188493ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:32.33179Z","caller":"traceutil/trace.go:171","msg":"trace[804497062] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"211.888451ms","start":"2026-05-06T00:43:32.119193Z","end":"2026-05-06T00:43:32.331082Z","steps":["trace[804497062] 'read index received' (duration: 124.171047ms)","trace[804497062] 'applied index is now lower than readState.Index' (duration: 87.716254ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:34.581739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.426963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:43:34.581851Z","caller":"traceutil/trace.go:171","msg":"trace[1460787790] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"537.273018ms","start":"2026-05-06T00:43:34.044562Z","end":"2026-05-06T00:43:34.581835Z","steps":["trace[1460787790] 'process raft request' (duration: 113.667174ms)","trace[1460787790] 'compare' (duration: 423.295992ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:43:34.581899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:34.044474Z","time spent":"537.402739ms","remote":"127.0.0.1:37920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4387,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:43:34.597917Z","caller":"traceutil/trace.go:171","msg":"trace[1384975563] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"547.062924ms","start":"2026-05-06T00:43:34.050822Z","end":"2026-05-06T00:43:34.597885Z","steps":["trace[1384975563] 'process raft request' (duration: 546.815202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:34.598124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:34.050799Z","time spent":"547.204475ms","remote":"127.0.0.1:37472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:43:34.598974Z","caller":"traceutil/trace.go:171","msg":"trace[2077493851] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"511.315613ms","start":"2026-05-06T00:43:34.087643Z","end":"2026-05-06T00:43:34.598959Z","steps":["trace[2077493851] 'read index received' (duration: 70.613138ms)","trace[2077493851] 'applied index is now lower than readState.Index' (duration: 440.701855ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:34.599021Z","caller":"traceutil/trace.go:171","msg":"trace[1811647030] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"474.716784ms","start":"2026-05-06T00:43:34.124288Z","end":"2026-05-06T00:43:34.599005Z","steps":["trace[1811647030] 'process raft request' (duration: 474.108139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:34.599089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:34.124259Z","time spent":"474.797374ms","remote":"127.0.0.1:37512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:43:34.599222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.565644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:43:34.599381Z","caller":"traceutil/trace.go:171","msg":"trace[2053227075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"511.768146ms","start":"2026-05-06T00:43:34.087604Z","end":"2026-05-06T00:43:34.599373Z","steps":["trace[2053227075] 'agreement among raft nodes before linearized reading' (duration: 511.451523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:34.599411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:34.087583Z","time spent":"511.820206ms","remote":"127.0.0.1:37678","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-05-06T00:43:34.604266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.699725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-06T00:43:34.604303Z","caller":"traceutil/trace.go:171","msg":"trace[167753637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"490.771395ms","start":"2026-05-06T00:43:34.113524Z","end":"2026-05-06T00:43:34.604295Z","steps":["trace[167753637] 'agreement among raft nodes before linearized reading' (duration: 490.663394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:34.604352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:34.113507Z","time spent":"490.813626ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T00:43:34.604379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.177502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:34.604429Z","caller":"traceutil/trace.go:171","msg":"trace[763218843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"117.226662ms","start":"2026-05-06T00:43:34.487187Z","end":"2026-05-06T00:43:34.604414Z","steps":["trace[763218843] 'agreement among raft nodes before linearized reading' (duration: 117.145091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:34.604443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.392402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:34.604512Z","caller":"traceutil/trace.go:171","msg":"trace[1348399592] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:608; }","duration":"369.518872ms","start":"2026-05-06T00:43:34.234977Z","end":"2026-05-06T00:43:34.604496Z","steps":["trace[1348399592] 'agreement among raft nodes before linearized reading' (duration: 369.381271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:34.604562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:34.234953Z","time spent":"369.596733ms","remote":"127.0.0.1:37472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-05-06T00:43:34.604578Z","caller":"traceutil/trace.go:171","msg":"trace[762056305] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"167.505945ms","start":"2026-05-06T00:43:34.437061Z","end":"2026-05-06T00:43:34.604567Z","steps":["trace[762056305] 'process raft request' (duration: 167.028221ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:37.001221Z","caller":"traceutil/trace.go:171","msg":"trace[1556106016] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"137.656746ms","start":"2026-05-06T00:43:36.863545Z","end":"2026-05-06T00:43:37.001202Z","steps":["trace[1556106016] 'process raft request' (duration: 137.513825ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:37.261907Z","caller":"traceutil/trace.go:171","msg":"trace[310282930] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"175.598907ms","start":"2026-05-06T00:43:37.086278Z","end":"2026-05-06T00:43:37.261877Z","steps":["trace[310282930] 'process raft request' (duration: 174.787851ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:37.262401Z","caller":"traceutil/trace.go:171","msg":"trace[1335620551] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"171.072572ms","start":"2026-05-06T00:43:37.091313Z","end":"2026-05-06T00:43:37.262385Z","steps":["trace[1335620551] 'process raft request' (duration: 170.609179ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:38.420018Z","caller":"traceutil/trace.go:171","msg":"trace[1729444364] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"134.943537ms","start":"2026-05-06T00:43:38.285059Z","end":"2026-05-06T00:43:38.420002Z","steps":["trace[1729444364] 'process raft request' (duration: 134.645654ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:38.420609Z","caller":"traceutil/trace.go:171","msg":"trace[1158579375] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"122.132371ms","start":"2026-05-06T00:43:38.298455Z","end":"2026-05-06T00:43:38.420587Z","steps":["trace[1158579375] 'read index received' (duration: 99.901085ms)","trace[1158579375] 'applied index is now lower than readState.Index' (duration: 22.227996ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:38.420656Z","caller":"traceutil/trace.go:171","msg":"trace[694056147] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"115.924615ms","start":"2026-05-06T00:43:38.304703Z","end":"2026-05-06T00:43:38.420628Z","steps":["trace[694056147] 'process raft request' (duration: 115.393022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:38.420824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.330353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:38.420928Z","caller":"traceutil/trace.go:171","msg":"trace[1731075219] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:647; }","duration":"122.474143ms","start":"2026-05-06T00:43:38.298432Z","end":"2026-05-06T00:43:38.420906Z","steps":["trace[1731075219] 'agreement among raft nodes before linearized reading' (duration: 122.284482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:38.425796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.27207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:38.425887Z","caller":"traceutil/trace.go:171","msg":"trace[1949762103] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:648; }","duration":"127.374731ms","start":"2026-05-06T00:43:38.298491Z","end":"2026-05-06T00:43:38.425865Z","steps":["trace[1949762103] 'agreement among raft nodes before linearized reading' (duration: 127.26256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:38.42606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.562182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:43:38.42612Z","caller":"traceutil/trace.go:171","msg":"trace[1704890654] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:648; }","duration":"127.602602ms","start":"2026-05-06T00:43:38.298485Z","end":"2026-05-06T00:43:38.426088Z","steps":["trace[1704890654] 'agreement among raft nodes before linearized reading' (duration: 127.546002ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:41.180438Z","caller":"traceutil/trace.go:171","msg":"trace[109700229] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"198.948294ms","start":"2026-05-06T00:43:40.981465Z","end":"2026-05-06T00:43:41.180414Z","steps":["trace[109700229] 'process raft request' (duration: 198.771663ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:41.645565Z","caller":"traceutil/trace.go:171","msg":"trace[827064955] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"106.920716ms","start":"2026-05-06T00:43:41.538624Z","end":"2026-05-06T00:43:41.645545Z","steps":["trace[827064955] 'process raft request' (duration: 106.610394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:42.988925Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411055743187644,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:43:43.454387Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.449240111s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:43:43.45479Z","caller":"traceutil/trace.go:171","msg":"trace[1086983220] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"966.832367ms","start":"2026-05-06T00:43:42.487935Z","end":"2026-05-06T00:43:43.454768Z","steps":["trace[1086983220] 'read index received' (duration: 966.639015ms)","trace[1086983220] 'applied index is now lower than readState.Index' (duration: 192.462µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:43:43.454831Z","caller":"traceutil/trace.go:171","msg":"trace[16718961] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"1.449754944s","start":"2026-05-06T00:43:42.005059Z","end":"2026-05-06T00:43:43.454814Z","steps":["trace[16718961] 'process raft request' (duration: 1.449557953s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:43.454948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.006388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:43:43.454959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:42.005043Z","time spent":"1.449838935s","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:43:43.454993Z","caller":"traceutil/trace.go:171","msg":"trace[278760425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:670; }","duration":"967.090218ms","start":"2026-05-06T00:43:42.487889Z","end":"2026-05-06T00:43:43.454979Z","steps":["trace[278760425] 'agreement among raft nodes before linearized reading' (duration: 966.988198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:43.455034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:42.48787Z","time spent":"967.152839ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:43:43.516983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.415332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-06T00:43:43.516996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.543497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T00:43:43.517069Z","caller":"traceutil/trace.go:171","msg":"trace[918291586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:670; }","duration":"835.499353ms","start":"2026-05-06T00:43:42.681539Z","end":"2026-05-06T00:43:43.517038Z","steps":["trace[918291586] 'agreement among raft nodes before linearized reading' (duration: 835.285851ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:43:43.51709Z","caller":"traceutil/trace.go:171","msg":"trace[486762650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:670; }","duration":"826.690289ms","start":"2026-05-06T00:43:42.690375Z","end":"2026-05-06T00:43:43.517065Z","steps":["trace[486762650] 'agreement among raft nodes before linearized reading' (duration: 826.442837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:43:43.517115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:42.681494Z","time spent":"835.611964ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T00:43:43.517143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:42.690345Z","time spent":"826.785419ms","remote":"127.0.0.1:37678","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-05-06T00:43:48.270236Z","caller":"traceutil/trace.go:171","msg":"trace[1879539331] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"243.871787ms","start":"2026-05-06T00:43:48.026326Z","end":"2026-05-06T00:43:48.270198Z","steps":["trace[1879539331] 'process raft request' (duration: 241.819611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:00.77614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149219552s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:44:00.776244Z","caller":"traceutil/trace.go:171","msg":"trace[2076872849] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"1.161861177s","start":"2026-05-06T00:43:59.614368Z","end":"2026-05-06T00:44:00.776229Z","steps":["trace[2076872849] 'read index received' (duration: 12.435775ms)","trace[2076872849] 'applied index is now lower than readState.Index' (duration: 1.149423962s)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:44:00.77636Z","caller":"traceutil/trace.go:171","msg":"trace[124539765] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"1.163481764s","start":"2026-05-06T00:43:59.612845Z","end":"2026-05-06T00:44:00.776326Z","steps":["trace[124539765] 'process raft request' (duration: 14.0052ms)","trace[124539765] 'compare' (duration: 1.14876771s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:44:00.776442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162080158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T00:44:00.776477Z","caller":"traceutil/trace.go:171","msg":"trace[503700823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"1.162151981s","start":"2026-05-06T00:43:59.614315Z","end":"2026-05-06T00:44:00.776467Z","steps":["trace[503700823] 'agreement among raft nodes before linearized reading' (duration: 1.161995624s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:00.776517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:59.614295Z","time spent":"1.162214064s","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:44:00.776527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:59.612814Z","time spent":"1.163626691s","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:44:00.77675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.882833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:44:00.77679Z","caller":"traceutil/trace.go:171","msg":"trace[1590178918] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:784; }","duration":"620.958166ms","start":"2026-05-06T00:44:00.155823Z","end":"2026-05-06T00:44:00.776781Z","steps":["trace[1590178918] 'agreement among raft nodes before linearized reading' (duration: 620.894953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:00.776795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039313932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-06T00:44:00.776822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:00.155799Z","time spent":"621.014569ms","remote":"127.0.0.1:37694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:44:00.776849Z","caller":"traceutil/trace.go:171","msg":"trace[1613579851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"1.039398635s","start":"2026-05-06T00:43:59.737433Z","end":"2026-05-06T00:44:00.776832Z","steps":["trace[1613579851] 'agreement among raft nodes before linearized reading' (duration: 1.039244148s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:00.776892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:59.737411Z","time spent":"1.039470879s","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T00:44:00.777034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.819775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T00:44:00.777102Z","caller":"traceutil/trace.go:171","msg":"trace[1075782885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"941.92523ms","start":"2026-05-06T00:43:59.835161Z","end":"2026-05-06T00:44:00.777086Z","steps":["trace[1075782885] 'agreement among raft nodes before linearized reading' (duration: 941.769213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:00.777041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.466208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v8xr7\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-05-06T00:44:00.77715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:43:59.835137Z","time spent":"942.003464ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T00:44:00.777182Z","caller":"traceutil/trace.go:171","msg":"trace[360018683] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v8xr7; range_end:; response_count:1; response_revision:784; }","duration":"703.635646ms","start":"2026-05-06T00:44:00.073531Z","end":"2026-05-06T00:44:00.777167Z","steps":["trace[360018683] 'agreement among raft nodes before linearized reading' (duration: 703.353423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:00.777271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:00.073513Z","time spent":"703.747971ms","remote":"127.0.0.1:37600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v8xr7\" "} {"level":"warn","ts":"2026-05-06T00:44:00.778081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.564568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:44:00.778255Z","caller":"traceutil/trace.go:171","msg":"trace[1136393280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"291.769148ms","start":"2026-05-06T00:44:00.486464Z","end":"2026-05-06T00:44:00.778233Z","steps":["trace[1136393280] 'agreement among raft nodes before linearized reading' (duration: 291.514806ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:01.068421Z","caller":"traceutil/trace.go:171","msg":"trace[158006346] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"286.350226ms","start":"2026-05-06T00:44:00.782046Z","end":"2026-05-06T00:44:01.068396Z","steps":["trace[158006346] 'process raft request' (duration: 283.932913ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:01.074405Z","caller":"traceutil/trace.go:171","msg":"trace[371146262] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"292.052492ms","start":"2026-05-06T00:44:00.782338Z","end":"2026-05-06T00:44:01.074391Z","steps":["trace[371146262] 'process raft request' (duration: 291.953907ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:01.074428Z","caller":"traceutil/trace.go:171","msg":"trace[2041672666] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"292.061022ms","start":"2026-05-06T00:44:00.782355Z","end":"2026-05-06T00:44:01.074416Z","steps":["trace[2041672666] 'process raft request' (duration: 291.983098ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:01.074587Z","caller":"traceutil/trace.go:171","msg":"trace[1030297620] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"290.588242ms","start":"2026-05-06T00:44:00.78399Z","end":"2026-05-06T00:44:01.074578Z","steps":["trace[1030297620] 'process raft request' (duration: 290.376163ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:01.074706Z","caller":"traceutil/trace.go:171","msg":"trace[361989573] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:813; }","duration":"201.363073ms","start":"2026-05-06T00:44:00.873333Z","end":"2026-05-06T00:44:01.074696Z","steps":["trace[361989573] 'read index received' (duration: 192.658825ms)","trace[361989573] 'applied index is now lower than readState.Index' (duration: 8.703488ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:44:01.074794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.456137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:44:01.074867Z","caller":"traceutil/trace.go:171","msg":"trace[85291111] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:789; }","duration":"201.582503ms","start":"2026-05-06T00:44:00.873272Z","end":"2026-05-06T00:44:01.074855Z","steps":["trace[85291111] 'agreement among raft nodes before linearized reading' (duration: 201.463808ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:06.499987Z","caller":"traceutil/trace.go:171","msg":"trace[1287686227] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"352.680335ms","start":"2026-05-06T00:44:06.147284Z","end":"2026-05-06T00:44:06.499965Z","steps":["trace[1287686227] 'process raft request' (duration: 352.426804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:06.500087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:06.147269Z","time spent":"352.770979ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:44:48.878819Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411055743188488,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:44:49.039328Z","caller":"traceutil/trace.go:171","msg":"trace[1924705092] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1113; }","duration":"660.948237ms","start":"2026-05-06T00:44:48.378348Z","end":"2026-05-06T00:44:49.039297Z","steps":["trace[1924705092] 'read index received' (duration: 658.053666ms)","trace[1924705092] 'applied index is now lower than readState.Index' (duration: 2.893581ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:44:49.039507Z","caller":"traceutil/trace.go:171","msg":"trace[981213315] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"755.228641ms","start":"2026-05-06T00:44:48.28426Z","end":"2026-05-06T00:44:49.039489Z","steps":["trace[981213315] 'process raft request' (duration: 752.155485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:49.039667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:48.284242Z","time spent":"755.325294ms","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:44:49.040052Z","caller":"traceutil/trace.go:171","msg":"trace[882763246] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"342.650004ms","start":"2026-05-06T00:44:48.697386Z","end":"2026-05-06T00:44:49.040036Z","steps":["trace[882763246] 'process raft request' (duration: 341.829851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:49.04014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:48.697297Z","time spent":"342.799008ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:44:49.040358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.999326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:44:49.040415Z","caller":"traceutil/trace.go:171","msg":"trace[698210636] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1075; }","duration":"662.10281ms","start":"2026-05-06T00:44:48.378298Z","end":"2026-05-06T00:44:49.040401Z","steps":["trace[698210636] 'agreement among raft nodes before linearized reading' (duration: 662.013766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:49.040464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:48.378274Z","time spent":"662.176842ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:44:49.040689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.622868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:44:49.040731Z","caller":"traceutil/trace.go:171","msg":"trace[1311246908] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1075; }","duration":"105.71067ms","start":"2026-05-06T00:44:48.935008Z","end":"2026-05-06T00:44:49.040719Z","steps":["trace[1311246908] 'agreement among raft nodes before linearized reading' (duration: 105.652169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.512377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.780652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:44:50.512472Z","caller":"traceutil/trace.go:171","msg":"trace[2001735687] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1076; }","duration":"476.945387ms","start":"2026-05-06T00:44:50.035504Z","end":"2026-05-06T00:44:50.51245Z","steps":["trace[2001735687] 'count revisions from in-memory index tree' (duration: 476.69296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.512473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052587094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-06T00:44:50.51256Z","caller":"traceutil/trace.go:171","msg":"trace[1117410741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1076; }","duration":"1.052725468s","start":"2026-05-06T00:44:49.459817Z","end":"2026-05-06T00:44:50.512542Z","steps":["trace[1117410741] 'range keys from in-memory index tree' (duration: 1.05242576s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.512601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:49.459795Z","time spent":"1.05279583s","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T00:44:50.512697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026015727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:44:50.512516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:50.035481Z","time spent":"477.025858ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:44:50.512772Z","caller":"traceutil/trace.go:171","msg":"trace[1122028669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"1.02614136s","start":"2026-05-06T00:44:49.486611Z","end":"2026-05-06T00:44:50.512753Z","steps":["trace[1122028669] 'range keys from in-memory index tree' (duration: 1.025929584s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.512804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:49.486591Z","time spent":"1.026206582s","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:44:50.51289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.155783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:44:50.512916Z","caller":"traceutil/trace.go:171","msg":"trace[29238206] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1076; }","duration":"477.192213ms","start":"2026-05-06T00:44:50.035717Z","end":"2026-05-06T00:44:50.512909Z","steps":["trace[29238206] 'count revisions from in-memory index tree' (duration: 477.129352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.512937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:50.035712Z","time spent":"477.219414ms","remote":"127.0.0.1:37556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:44:50.512885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.187266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-06T00:44:50.512998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.05793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:44:50.51303Z","caller":"traceutil/trace.go:171","msg":"trace[188194260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1076; }","duration":"477.121121ms","start":"2026-05-06T00:44:50.035899Z","end":"2026-05-06T00:44:50.51302Z","steps":["trace[188194260] 'range keys from in-memory index tree' (duration: 476.982278ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:44:50.513022Z","caller":"traceutil/trace.go:171","msg":"trace[674567831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1076; }","duration":"965.372411ms","start":"2026-05-06T00:44:49.547621Z","end":"2026-05-06T00:44:50.512994Z","steps":["trace[674567831] 'range keys from in-memory index tree' (duration: 964.97103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.513054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:50.03588Z","time spent":"477.167682ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T00:44:50.513088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:49.547602Z","time spent":"965.473174ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T00:44:50.513291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.456082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T00:44:50.513334Z","caller":"traceutil/trace.go:171","msg":"trace[666225119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1076; }","duration":"961.511573ms","start":"2026-05-06T00:44:49.551815Z","end":"2026-05-06T00:44:50.513327Z","steps":["trace[666225119] 'range keys from in-memory index tree' (duration: 961.226045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.513351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:49.5518Z","time spent":"961.547053ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:44:50.538593Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411055743188506,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:44:50.575592Z","caller":"traceutil/trace.go:171","msg":"trace[1329159807] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"537.500434ms","start":"2026-05-06T00:44:50.038059Z","end":"2026-05-06T00:44:50.57556Z","steps":["trace[1329159807] 'process raft request' (duration: 537.284188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.575787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:50.038042Z","time spent":"537.647118ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:44:50.576817Z","caller":"traceutil/trace.go:171","msg":"trace[1081628955] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1116; }","duration":"538.595104ms","start":"2026-05-06T00:44:50.038196Z","end":"2026-05-06T00:44:50.576791Z","steps":["trace[1081628955] 'read index received' (duration: 537.113573ms)","trace[1081628955] 'applied index is now lower than readState.Index' (duration: 1.480871ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:44:50.577079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.983998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-06T00:44:50.577081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.866122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:44:50.577155Z","caller":"traceutil/trace.go:171","msg":"trace[472468737] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1081; }","duration":"538.990535ms","start":"2026-05-06T00:44:50.038149Z","end":"2026-05-06T00:44:50.577139Z","steps":["trace[472468737] 'agreement among raft nodes before linearized reading' (duration: 538.857361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.577194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:50.038076Z","time spent":"539.108608ms","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":69,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:44:50.57712Z","caller":"traceutil/trace.go:171","msg":"trace[301512619] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1081; }","duration":"402.106111ms","start":"2026-05-06T00:44:50.175006Z","end":"2026-05-06T00:44:50.577113Z","steps":["trace[301512619] 'agreement among raft nodes before linearized reading' (duration: 402.008978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:44:50.57731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:44:50.174982Z","time spent":"402.300537ms","remote":"127.0.0.1:37520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":16,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:44:51.517669Z","caller":"traceutil/trace.go:171","msg":"trace[2046122746] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"148.688154ms","start":"2026-05-06T00:44:51.368951Z","end":"2026-05-06T00:44:51.51764Z","steps":["trace[2046122746] 'process raft request' (duration: 148.53554ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:02.812066Z","caller":"traceutil/trace.go:171","msg":"trace[2016196540] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"184.077944ms","start":"2026-05-06T00:45:02.627965Z","end":"2026-05-06T00:45:02.812043Z","steps":["trace[2016196540] 'process raft request' (duration: 183.93497ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:02.824066Z","caller":"traceutil/trace.go:171","msg":"trace[2126163418] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1159; }","duration":"189.078839ms","start":"2026-05-06T00:45:02.634957Z","end":"2026-05-06T00:45:02.824036Z","steps":["trace[2126163418] 'read index received' (duration: 176.930864ms)","trace[2126163418] 'applied index is now lower than readState.Index' (duration: 12.147015ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:02.824168Z","caller":"traceutil/trace.go:171","msg":"trace[1077892851] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"193.089549ms","start":"2026-05-06T00:45:02.631067Z","end":"2026-05-06T00:45:02.824157Z","steps":["trace[1077892851] 'process raft request' (duration: 192.849723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:02.824398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.420518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T00:45:02.824433Z","caller":"traceutil/trace.go:171","msg":"trace[1464262493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1119; }","duration":"189.48194ms","start":"2026-05-06T00:45:02.634942Z","end":"2026-05-06T00:45:02.824423Z","steps":["trace[1464262493] 'agreement among raft nodes before linearized reading' (duration: 189.329716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:02.824595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.199437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:45:02.824673Z","caller":"traceutil/trace.go:171","msg":"trace[863496662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1119; }","duration":"188.30797ms","start":"2026-05-06T00:45:02.636348Z","end":"2026-05-06T00:45:02.824656Z","steps":["trace[863496662] 'agreement among raft nodes before linearized reading' (duration: 188.119835ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:03.71989Z","caller":"traceutil/trace.go:171","msg":"trace[2105348451] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"158.417738ms","start":"2026-05-06T00:45:03.561447Z","end":"2026-05-06T00:45:03.719865Z","steps":["trace[2105348451] 'read index received' (duration: 117.592153ms)","trace[2105348451] 'applied index is now lower than readState.Index' (duration: 40.824295ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:03.720116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.639573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T00:45:03.720155Z","caller":"traceutil/trace.go:171","msg":"trace[1729159267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1122; }","duration":"158.707045ms","start":"2026-05-06T00:45:03.561438Z","end":"2026-05-06T00:45:03.720145Z","steps":["trace[1729159267] 'agreement among raft nodes before linearized reading' (duration: 158.52397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:07.12637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.355533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:45:07.126479Z","caller":"traceutil/trace.go:171","msg":"trace[811363992] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1174; }","duration":"202.392564ms","start":"2026-05-06T00:45:06.924065Z","end":"2026-05-06T00:45:07.126458Z","steps":["trace[811363992] 'read index received' (duration: 58.767525ms)","trace[811363992] 'applied index is now lower than readState.Index' (duration: 143.623579ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:07.126643Z","caller":"traceutil/trace.go:171","msg":"trace[165296992] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"207.024806ms","start":"2026-05-06T00:45:06.91959Z","end":"2026-05-06T00:45:07.126614Z","steps":["trace[165296992] 'process raft request' (duration: 63.341375ms)","trace[165296992] 'compare' (duration: 143.161307ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:07.126701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.626589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T00:45:07.126749Z","caller":"traceutil/trace.go:171","msg":"trace[807776483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1132; }","duration":"202.684271ms","start":"2026-05-06T00:45:06.924054Z","end":"2026-05-06T00:45:07.126738Z","steps":["trace[807776483] 'agreement among raft nodes before linearized reading' (duration: 202.532267ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:15.089571Z","caller":"traceutil/trace.go:171","msg":"trace[323509264] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"114.787717ms","start":"2026-05-06T00:45:14.97476Z","end":"2026-05-06T00:45:15.089548Z","steps":["trace[323509264] 'process raft request' (duration: 114.647813ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:42.903848Z","caller":"traceutil/trace.go:171","msg":"trace[355109896] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"416.381583ms","start":"2026-05-06T00:45:42.487442Z","end":"2026-05-06T00:45:42.903824Z","steps":["trace[355109896] 'read index received' (duration: 416.2498ms)","trace[355109896] 'applied index is now lower than readState.Index' (duration: 130.853µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:42.904079Z","caller":"traceutil/trace.go:171","msg":"trace[694167576] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"736.273542ms","start":"2026-05-06T00:45:42.16779Z","end":"2026-05-06T00:45:42.904063Z","steps":["trace[694167576] 'process raft request' (duration: 735.892865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:42.904193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:42.167762Z","time spent":"736.365323ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:45:42.904193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.722919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:42.904419Z","caller":"traceutil/trace.go:171","msg":"trace[638484354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"417.003794ms","start":"2026-05-06T00:45:42.487391Z","end":"2026-05-06T00:45:42.904394Z","steps":["trace[638484354] 'agreement among raft nodes before linearized reading' (duration: 416.698968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:42.904469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:42.487374Z","time spent":"417.083685ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:45:42.90705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.44063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efdf2dab-3f69-414c-8bc5-3afe1a213b30\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-05-06T00:45:42.907152Z","caller":"traceutil/trace.go:171","msg":"trace[1003445723] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-efdf2dab-3f69-414c-8bc5-3afe1a213b30; range_end:; response_count:1; response_revision:1261; }","duration":"344.582943ms","start":"2026-05-06T00:45:42.562537Z","end":"2026-05-06T00:45:42.90712Z","steps":["trace[1003445723] 'agreement among raft nodes before linearized reading' (duration: 344.283788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:42.907358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:42.562519Z","time spent":"344.706935ms","remote":"127.0.0.1:37600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3602,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efdf2dab-3f69-414c-8bc5-3afe1a213b30\" "} {"level":"warn","ts":"2026-05-06T00:45:46.962149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.717597467s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T00:45:46.962212Z","caller":"traceutil/trace.go:171","msg":"trace[717389454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1266; }","duration":"1.717717129s","start":"2026-05-06T00:45:45.244481Z","end":"2026-05-06T00:45:46.962198Z","steps":["trace[717389454] 'range keys from in-memory index tree' (duration: 1.717373493s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:46.962243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:45.244458Z","time spent":"1.71777864s","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T00:45:46.962719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.791629539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:45:46.962749Z","caller":"traceutil/trace.go:171","msg":"trace[4496070] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1266; }","duration":"2.791701131s","start":"2026-05-06T00:45:44.17104Z","end":"2026-05-06T00:45:46.962741Z","steps":["trace[4496070] 'count revisions from in-memory index tree' (duration: 2.791542118s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:46.96277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:44.171005Z","time spent":"2.791758631s","remote":"127.0.0.1:37920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:45:46.962812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.618496509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:45:46.962939Z","caller":"traceutil/trace.go:171","msg":"trace[161649489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1266; }","duration":"1.618671902s","start":"2026-05-06T00:45:45.344249Z","end":"2026-05-06T00:45:46.962921Z","steps":["trace[161649489] 'range keys from in-memory index tree' (duration: 1.618346796s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:46.962991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999061516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efdf2dab-3f69-414c-8bc5-3afe1a213b30\" ","response":"range_response_count:1 size:3578"} {"level":"warn","ts":"2026-05-06T00:45:46.963057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:45.34423Z","time spent":"1.618814515s","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T00:45:46.963089Z","caller":"traceutil/trace.go:171","msg":"trace[1745347478] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-efdf2dab-3f69-414c-8bc5-3afe1a213b30; range_end:; response_count:1; response_revision:1266; }","duration":"1.999198419s","start":"2026-05-06T00:45:44.963866Z","end":"2026-05-06T00:45:46.963064Z","steps":["trace[1745347478] 'range keys from in-memory index tree' (duration: 1.998889624s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:46.963174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:44.963846Z","time spent":"1.999311361s","remote":"127.0.0.1:37600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3602,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efdf2dab-3f69-414c-8bc5-3afe1a213b30\" "} {"level":"warn","ts":"2026-05-06T00:45:46.963417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585012461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:46.963559Z","caller":"traceutil/trace.go:171","msg":"trace[1904026817] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1266; }","duration":"1.585189863s","start":"2026-05-06T00:45:45.378347Z","end":"2026-05-06T00:45:46.963536Z","steps":["trace[1904026817] 'count revisions from in-memory index tree' (duration: 1.584912478s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:46.963667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:45.378329Z","time spent":"1.585316825s","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:45:46.963734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487042573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-05-06T00:45:46.963785Z","caller":"traceutil/trace.go:171","msg":"trace[1735893102] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1266; }","duration":"1.487119954s","start":"2026-05-06T00:45:45.476652Z","end":"2026-05-06T00:45:46.963772Z","steps":["trace[1735893102] 'range keys from in-memory index tree' (duration: 1.486933741s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:46.96382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:45.476641Z","time spent":"1.487171734s","remote":"127.0.0.1:37600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1514,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-05-06T00:45:46.96377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.857084642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-06T00:45:46.962811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.475528511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:46.963903Z","caller":"traceutil/trace.go:171","msg":"trace[685029184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1266; }","duration":"1.857226794s","start":"2026-05-06T00:45:45.106628Z","end":"2026-05-06T00:45:46.963855Z","steps":["trace[685029184] 'range keys from in-memory index tree' (duration: 1.856884199s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:46.96389Z","caller":"traceutil/trace.go:171","msg":"trace[1906935196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"2.476679042s","start":"2026-05-06T00:45:44.487196Z","end":"2026-05-06T00:45:46.963875Z","steps":["trace[1906935196] 'range keys from in-memory index tree' (duration: 2.475418269s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:46.964009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493351059s","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-05-06T00:45:46.964003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:45.106608Z","time spent":"1.857377487s","remote":"127.0.0.1:37678","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-05-06T00:45:46.963966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:44.487174Z","time spent":"2.476778904s","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:45:46.964045Z","caller":"traceutil/trace.go:171","msg":"trace[1593672512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1266; }","duration":"1.493408209s","start":"2026-05-06T00:45:45.470627Z","end":"2026-05-06T00:45:46.964035Z","steps":["trace[1593672512] 'range keys from in-memory index tree' (duration: 1.493248717s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:46.964233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.05369501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-06T00:45:46.96432Z","caller":"traceutil/trace.go:171","msg":"trace[107643158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1266; }","duration":"2.055646386s","start":"2026-05-06T00:45:44.908628Z","end":"2026-05-06T00:45:46.964274Z","steps":["trace[107643158] 'range keys from in-memory index tree' (duration: 2.053506477s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:46.964217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:45.470613Z","time spent":"1.493593592s","remote":"127.0.0.1:37678","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-05-06T00:45:46.96493Z","caller":"traceutil/trace.go:171","msg":"trace[1162487132] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1318; }","duration":"476.81633ms","start":"2026-05-06T00:45:46.488098Z","end":"2026-05-06T00:45:46.964915Z","steps":["trace[1162487132] 'read index received' (duration: 472.214985ms)","trace[1162487132] 'applied index is now lower than readState.Index' (duration: 4.600235ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:46.965283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.158787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:45:46.965183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.092235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:46.965484Z","caller":"traceutil/trace.go:171","msg":"trace[149593326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"477.435631ms","start":"2026-05-06T00:45:46.488038Z","end":"2026-05-06T00:45:46.965474Z","steps":["trace[149593326] 'agreement among raft nodes before linearized reading' (duration: 477.116486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:46.965513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:46.488019Z","time spent":"477.486522ms","remote":"127.0.0.1:37428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:45:46.965665Z","caller":"traceutil/trace.go:171","msg":"trace[33213757] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"997.69568ms","start":"2026-05-06T00:45:45.967957Z","end":"2026-05-06T00:45:46.965653Z","steps":["trace[33213757] 'process raft request' (duration: 992.413193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:46.965736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:45.967927Z","time spent":"997.760822ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:46.966115Z","caller":"traceutil/trace.go:171","msg":"trace[521317324] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1267; }","duration":"446.301789ms","start":"2026-05-06T00:45:46.519066Z","end":"2026-05-06T00:45:46.965367Z","steps":["trace[521317324] 'agreement among raft nodes before linearized reading' (duration: 446.135716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:46.966189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:46.519043Z","time spent":"447.107503ms","remote":"127.0.0.1:47800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:45:46.96448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:44.908605Z","time spent":"2.05586271s","remote":"127.0.0.1:37678","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-05-06T00:45:47.124837Z","caller":"traceutil/trace.go:171","msg":"trace[1820346318] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"154.140145ms","start":"2026-05-06T00:45:46.970667Z","end":"2026-05-06T00:45:47.124807Z","steps":["trace[1820346318] 'process raft request' (duration: 110.849685ms)","trace[1820346318] 'compare' (duration: 43.164708ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:47.781338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.156378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:45:47.781701Z","caller":"traceutil/trace.go:171","msg":"trace[1458119881] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"810.941348ms","start":"2026-05-06T00:45:46.970726Z","end":"2026-05-06T00:45:47.781667Z","steps":["trace[1458119881] 'process raft request' (duration: 352.316951ms)","trace[1458119881] 'compare' (duration: 457.883754ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:47.781772Z","caller":"traceutil/trace.go:171","msg":"trace[1019804998] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"810.922828ms","start":"2026-05-06T00:45:46.970823Z","end":"2026-05-06T00:45:47.781745Z","steps":["trace[1019804998] 'process raft request' (duration: 810.815076ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:47.781804Z","caller":"traceutil/trace.go:171","msg":"trace[1161845544] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"810.985389ms","start":"2026-05-06T00:45:46.970788Z","end":"2026-05-06T00:45:47.781773Z","steps":["trace[1161845544] 'process raft request' (duration: 810.784885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:47.781923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:46.970709Z","time spent":"811.071561ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:45:47.782026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:46.970816Z","time spent":"811.00829ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:47.782222Z","caller":"traceutil/trace.go:171","msg":"trace[2131255041] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"811.150612ms","start":"2026-05-06T00:45:46.971043Z","end":"2026-05-06T00:45:47.782194Z","steps":["trace[2131255041] 'process raft request' (duration: 810.635663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:47.782364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:46.970782Z","time spent":"811.275864ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:45:47.782521Z","caller":"traceutil/trace.go:171","msg":"trace[4514481] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1319; }","duration":"808.375353ms","start":"2026-05-06T00:45:46.974124Z","end":"2026-05-06T00:45:47.782499Z","steps":["trace[4514481] 'read index received' (duration: 107.407583ms)","trace[4514481] 'applied index is now lower than readState.Index' (duration: 700.96575ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:47.782578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:46.971036Z","time spent":"811.330476ms","remote":"127.0.0.1:37502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:45:47.782618Z","caller":"traceutil/trace.go:171","msg":"trace[46572349] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"810.998301ms","start":"2026-05-06T00:45:46.971606Z","end":"2026-05-06T00:45:47.782604Z","steps":["trace[46572349] 'process raft request' (duration: 810.427219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:47.782647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.538135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:47.782684Z","caller":"traceutil/trace.go:171","msg":"trace[1496827294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"808.609977ms","start":"2026-05-06T00:45:46.974063Z","end":"2026-05-06T00:45:47.782673Z","steps":["trace[1496827294] 'agreement among raft nodes before linearized reading' (duration: 808.512055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:47.782683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:46.971599Z","time spent":"811.048491ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:45:47.782887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:46.974053Z","time spent":"808.654747ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:45:48.988266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411055743189044,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:45:48.998783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:48.209696Z","time spent":"789.079525ms","remote":"127.0.0.1:37448","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-06T00:45:49.263366Z","caller":"traceutil/trace.go:171","msg":"trace[1015733706] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"288.677621ms","start":"2026-05-06T00:45:48.974646Z","end":"2026-05-06T00:45:49.263323Z","steps":["trace[1015733706] 'process raft request' (duration: 275.714967ms)","trace[1015733706] 'compare' (duration: 12.390903ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:49.263523Z","caller":"traceutil/trace.go:171","msg":"trace[806895038] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"263.496354ms","start":"2026-05-06T00:45:48.999997Z","end":"2026-05-06T00:45:49.263493Z","steps":["trace[806895038] 'process raft request' (duration: 263.221469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.2636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.747266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:45:49.263647Z","caller":"traceutil/trace.go:171","msg":"trace[469706972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"775.866508ms","start":"2026-05-06T00:45:48.487769Z","end":"2026-05-06T00:45:49.263636Z","steps":["trace[469706972] 'agreement among raft nodes before linearized reading' (duration: 775.755126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.263673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:45:48.487731Z","time spent":"775.93525ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:45:49.263403Z","caller":"traceutil/trace.go:171","msg":"trace[1699146416] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1326; }","duration":"775.475431ms","start":"2026-05-06T00:45:48.487854Z","end":"2026-05-06T00:45:49.263329Z","steps":["trace[1699146416] 'read index received' (duration: 510.355438ms)","trace[1699146416] 'applied index is now lower than readState.Index' (duration: 265.117603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:45:49.264433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.517911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efdf2dab-3f69-414c-8bc5-3afe1a213b30\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-05-06T00:45:49.264546Z","caller":"traceutil/trace.go:171","msg":"trace[1113976910] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-efdf2dab-3f69-414c-8bc5-3afe1a213b30; range_end:; response_count:1; response_revision:1276; }","duration":"286.660214ms","start":"2026-05-06T00:45:48.977864Z","end":"2026-05-06T00:45:49.264524Z","steps":["trace[1113976910] 'agreement among raft nodes before linearized reading' (duration: 286.305747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:49.264459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.334533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-06T00:45:49.264741Z","caller":"traceutil/trace.go:171","msg":"trace[218380689] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1276; }","duration":"134.648649ms","start":"2026-05-06T00:45:49.130013Z","end":"2026-05-06T00:45:49.264662Z","steps":["trace[218380689] 'agreement among raft nodes before linearized reading' (duration: 134.219351ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:49.993468Z","caller":"traceutil/trace.go:171","msg":"trace[240782752] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"137.867077ms","start":"2026-05-06T00:45:49.855582Z","end":"2026-05-06T00:45:49.993449Z","steps":["trace[240782752] 'process raft request' (duration: 129.507346ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:55.533457Z","caller":"traceutil/trace.go:171","msg":"trace[1648560264] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"231.787807ms","start":"2026-05-06T00:45:55.301644Z","end":"2026-05-06T00:45:55.533432Z","steps":["trace[1648560264] 'read index received' (duration: 231.464711ms)","trace[1648560264] 'applied index is now lower than readState.Index' (duration: 321.966µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:55.533575Z","caller":"traceutil/trace.go:171","msg":"trace[476870326] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"252.721263ms","start":"2026-05-06T00:45:55.280807Z","end":"2026-05-06T00:45:55.533529Z","steps":["trace[476870326] 'process raft request' (duration: 252.441308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:55.53399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.319427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T00:45:55.534065Z","caller":"traceutil/trace.go:171","msg":"trace[131219323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1317; }","duration":"232.445199ms","start":"2026-05-06T00:45:55.301602Z","end":"2026-05-06T00:45:55.534047Z","steps":["trace[131219323] 'agreement among raft nodes before linearized reading' (duration: 231.97673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:56.082988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.785854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-06T00:45:56.083008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.003222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-06T00:45:56.083088Z","caller":"traceutil/trace.go:171","msg":"trace[2082146466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1319; }","duration":"218.909436ms","start":"2026-05-06T00:45:55.864155Z","end":"2026-05-06T00:45:56.083065Z","steps":["trace[2082146466] 'range keys from in-memory index tree' (duration: 218.634941ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:56.083098Z","caller":"traceutil/trace.go:171","msg":"trace[1671476006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1319; }","duration":"217.163825ms","start":"2026-05-06T00:45:55.865912Z","end":"2026-05-06T00:45:56.083076Z","steps":["trace[1671476006] 'range keys from in-memory index tree' (duration: 216.841939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:45:56.082943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.438447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T00:45:56.083225Z","caller":"traceutil/trace.go:171","msg":"trace[401150187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1319; }","duration":"218.778453ms","start":"2026-05-06T00:45:55.864426Z","end":"2026-05-06T00:45:56.083205Z","steps":["trace[401150187] 'range keys from in-memory index tree' (duration: 218.239464ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:45:56.301138Z","caller":"traceutil/trace.go:171","msg":"trace[1651495887] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"213.491478ms","start":"2026-05-06T00:45:56.087621Z","end":"2026-05-06T00:45:56.301112Z","steps":["trace[1651495887] 'process raft request' (duration: 172.584738ms)","trace[1651495887] 'compare' (duration: 40.737747ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:45:56.301332Z","caller":"traceutil/trace.go:171","msg":"trace[966388167] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"212.751865ms","start":"2026-05-06T00:45:56.088555Z","end":"2026-05-06T00:45:56.301307Z","steps":["trace[966388167] 'process raft request' (duration: 212.51216ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:46:03.970689Z","caller":"traceutil/trace.go:171","msg":"trace[777564401] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"504.139605ms","start":"2026-05-06T00:46:03.466526Z","end":"2026-05-06T00:46:03.970666Z","steps":["trace[777564401] 'process raft request' (duration: 503.994583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:03.970842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:03.466507Z","time spent":"504.260077ms","remote":"127.0.0.1:37594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5897,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:46:03.974465Z","caller":"traceutil/trace.go:171","msg":"trace[1089512231] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1405; }","duration":"403.344415ms","start":"2026-05-06T00:46:03.571097Z","end":"2026-05-06T00:46:03.974441Z","steps":["trace[1089512231] 'read index received' (duration: 399.416419ms)","trace[1089512231] 'applied index is now lower than readState.Index' (duration: 3.927176ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:46:03.974575Z","caller":"traceutil/trace.go:171","msg":"trace[1902748488] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"415.790224ms","start":"2026-05-06T00:46:03.558774Z","end":"2026-05-06T00:46:03.974564Z","steps":["trace[1902748488] 'process raft request' (duration: 415.54391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:03.974823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:03.558747Z","time spent":"415.973806ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:46:03.975037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.937095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T00:46:03.975086Z","caller":"traceutil/trace.go:171","msg":"trace[2124828883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1351; }","duration":"403.995575ms","start":"2026-05-06T00:46:03.571061Z","end":"2026-05-06T00:46:03.975057Z","steps":["trace[2124828883] 'agreement among raft nodes before linearized reading' (duration: 403.888613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:03.97517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:46:03.571046Z","time spent":"404.101716ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T00:46:08.545878Z","caller":"traceutil/trace.go:171","msg":"trace[672824262] linearizableReadLoop","detail":"{readStateIndex:1431; appliedIndex:1430; }","duration":"147.362124ms","start":"2026-05-06T00:46:08.398481Z","end":"2026-05-06T00:46:08.545843Z","steps":["trace[672824262] 'read index received' (duration: 147.049509ms)","trace[672824262] 'applied index is now lower than readState.Index' (duration: 311.515µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:46:08.545939Z","caller":"traceutil/trace.go:171","msg":"trace[480713300] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"180.640809ms","start":"2026-05-06T00:46:08.365259Z","end":"2026-05-06T00:46:08.5459Z","steps":["trace[480713300] 'process raft request' (duration: 180.380025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:46:08.546179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.67492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T00:46:08.546258Z","caller":"traceutil/trace.go:171","msg":"trace[969306938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1372; }","duration":"147.803381ms","start":"2026-05-06T00:46:08.398436Z","end":"2026-05-06T00:46:08.54624Z","steps":["trace[969306938] 'agreement among raft nodes before linearized reading' (duration: 147.533776ms)"],"step_count":1}