{"level":"warn","ts":"2026-05-10T04:02:29.895755Z","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-10T04:02:29.895844Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-10T04:02:29.8959Z","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-10T04:02:29.895911Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-10T04:02:29.895926Z","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-10T04:02:29.896517Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-05-10T04:02:29.896581Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-10T04:02:29.901851Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.026896ms"} {"level":"info","ts":"2026-05-10T04:02:29.90796Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-05-10T04:02:29.908083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-05-10T04:02:29.908135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-05-10T04:02:29.908153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-10T04:02:29.908189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-05-10T04:02:29.908254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-05-10T04:02:29.913719Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-10T04:02:29.91521Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-10T04:02:29.916336Z","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-10T04:02:29.918618Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-10T04:02:29.919284Z","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-10T04:02:29.919405Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-10T04:02:29.919482Z","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-10T04:02:29.919521Z","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-10T04:02:29.919637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-05-10T04:02:29.921662Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-10T04:02:29.926129Z","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-10T04:02:29.926263Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-10T04:02:29.926303Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-10T04:02:29.926419Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-10T04:02:29.926505Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-10T04:02:30.708534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-05-10T04:02:30.708585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-10T04:02:30.708628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-05-10T04:02:30.70864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-05-10T04:02:30.708668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-10T04:02:30.708681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-05-10T04:02:30.708688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-10T04:02:30.710409Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-05-10T04:02:30.710573Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T04:02:30.710716Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T04:02:30.710774Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T04:02:30.711194Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-10T04:02:30.711884Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T04:02:30.711988Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T04:02:30.712019Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T04:02:30.714284Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-10T04:02:30.714705Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-05-10T04:02:30.71131Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-10T04:02:45.994512Z","caller":"traceutil/trace.go:171","msg":"trace[2061145418] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"324.208489ms","start":"2026-05-10T04:02:45.670275Z","end":"2026-05-10T04:02:45.994484Z","steps":["trace[2061145418] 'process raft request' (duration: 324.050946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:45.99526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:45.670251Z","time spent":"324.345784ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:02:46.705371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.792835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-10T04:02:46.705475Z","caller":"traceutil/trace.go:171","msg":"trace[952644836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:407; }","duration":"461.31872ms","start":"2026-05-10T04:02:46.244139Z","end":"2026-05-10T04:02:46.705458Z","steps":["trace[952644836] 'range keys from in-memory index tree' (duration: 460.707133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:46.705435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.775926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-10T04:02:46.705508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:46.244129Z","time spent":"461.37022ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T04:02:46.705519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.64465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-05-10T04:02:46.705551Z","caller":"traceutil/trace.go:171","msg":"trace[1320328464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"466.951411ms","start":"2026-05-10T04:02:46.238572Z","end":"2026-05-10T04:02:46.705524Z","steps":["trace[1320328464] 'range keys from in-memory index tree' (duration: 466.672314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:46.705385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.719906ms","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-10T04:02:46.70561Z","caller":"traceutil/trace.go:171","msg":"trace[1406377164] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:407; }","duration":"267.764293ms","start":"2026-05-10T04:02:46.437825Z","end":"2026-05-10T04:02:46.70559Z","steps":["trace[1406377164] 'range keys from in-memory index tree' (duration: 267.455086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:46.705638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:46.238553Z","time spent":"467.051664ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-10T04:02:46.705657Z","caller":"traceutil/trace.go:171","msg":"trace[60771950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"270.20955ms","start":"2026-05-10T04:02:46.435429Z","end":"2026-05-10T04:02:46.705638Z","steps":["trace[60771950] 'range keys from in-memory index tree' (duration: 269.654804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:46.705371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.236802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T04:02:46.705788Z","caller":"traceutil/trace.go:171","msg":"trace[1788775475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:407; }","duration":"195.703033ms","start":"2026-05-10T04:02:46.510075Z","end":"2026-05-10T04:02:46.705778Z","steps":["trace[1788775475] 'range keys from in-memory index tree' (duration: 195.146159ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:02:47.691785Z","caller":"traceutil/trace.go:171","msg":"trace[1833783510] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"982.225819ms","start":"2026-05-10T04:02:46.709533Z","end":"2026-05-10T04:02:47.691759Z","steps":["trace[1833783510] 'process raft request' (duration: 982.075045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:47.691946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:46.709505Z","time spent":"982.356054ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:02:47.951364Z","caller":"traceutil/trace.go:171","msg":"trace[188205022] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:420; }","duration":"679.067369ms","start":"2026-05-10T04:02:47.27227Z","end":"2026-05-10T04:02:47.951337Z","steps":["trace[188205022] 'read index received' (duration: 419.246401ms)","trace[188205022] 'applied index is now lower than readState.Index' (duration: 259.819757ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:02:47.95151Z","caller":"traceutil/trace.go:171","msg":"trace[1993067972] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.241167294s","start":"2026-05-10T04:02:46.710324Z","end":"2026-05-10T04:02:47.951492Z","steps":["trace[1993067972] 'process raft request' (duration: 1.240906667s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:47.9518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.50493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-10T04:02:47.951888Z","caller":"traceutil/trace.go:171","msg":"trace[1225512914] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:412; }","duration":"679.622263ms","start":"2026-05-10T04:02:47.272243Z","end":"2026-05-10T04:02:47.951866Z","steps":["trace[1225512914] 'agreement among raft nodes before linearized reading' (duration: 679.397467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:47.951943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:47.272228Z","time spent":"679.701536ms","remote":"127.0.0.1:53660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-05-10T04:02:47.951903Z","caller":"traceutil/trace.go:171","msg":"trace[415657567] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"1.242051038s","start":"2026-05-10T04:02:46.709804Z","end":"2026-05-10T04:02:47.951855Z","steps":["trace[415657567] 'process raft request' (duration: 1.241077041s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:47.951874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:46.710315Z","time spent":"1.241242275s","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:02:47.952137Z","caller":"traceutil/trace.go:171","msg":"trace[564765221] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"1.234956116s","start":"2026-05-10T04:02:46.717165Z","end":"2026-05-10T04:02:47.952121Z","steps":["trace[564765221] 'process raft request' (duration: 1.234118574s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:47.952221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:46.717157Z","time spent":"1.235017838s","remote":"127.0.0.1:53646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:02:47.952073Z","caller":"traceutil/trace.go:171","msg":"trace[616644739] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.24214351s","start":"2026-05-10T04:02:46.709913Z","end":"2026-05-10T04:02:47.952056Z","steps":["trace[616644739] 'process raft request' (duration: 1.241227166s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:02:47.953165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:46.709889Z","time spent":"1.24319943s","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:02:47.952189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:02:46.709796Z","time spent":"1.242241633s","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:02:50.063972Z","caller":"traceutil/trace.go:171","msg":"trace[240314079] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"100.640582ms","start":"2026-05-10T04:02:49.963307Z","end":"2026-05-10T04:02:50.063947Z","steps":["trace[240314079] 'process raft request' (duration: 100.576359ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:02:50.064119Z","caller":"traceutil/trace.go:171","msg":"trace[1800313042] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"101.002032ms","start":"2026-05-10T04:02:49.963106Z","end":"2026-05-10T04:02:50.064108Z","steps":["trace[1800313042] 'process raft request' (duration: 100.634472ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:01.530916Z","caller":"traceutil/trace.go:171","msg":"trace[248481063] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"195.636522ms","start":"2026-05-10T04:03:01.335247Z","end":"2026-05-10T04:03:01.530883Z","steps":["trace[248481063] 'read index received' (duration: 181.910831ms)","trace[248481063] 'applied index is now lower than readState.Index' (duration: 13.724411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:01.531174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.896789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-05-10T04:03:01.531065Z","caller":"traceutil/trace.go:171","msg":"trace[1382791381] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"197.447421ms","start":"2026-05-10T04:03:01.333575Z","end":"2026-05-10T04:03:01.531022Z","steps":["trace[1382791381] 'process raft request' (duration: 183.512214ms)","trace[1382791381] 'compare' (duration: 13.662669ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:01.531221Z","caller":"traceutil/trace.go:171","msg":"trace[1682830505] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:503; }","duration":"195.97925ms","start":"2026-05-10T04:03:01.335228Z","end":"2026-05-10T04:03:01.531207Z","steps":["trace[1682830505] 'agreement among raft nodes before linearized reading' (duration: 195.797046ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:01.535008Z","caller":"traceutil/trace.go:171","msg":"trace[268383293] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"198.001415ms","start":"2026-05-10T04:03:01.336978Z","end":"2026-05-10T04:03:01.53498Z","steps":["trace[268383293] 'process raft request' (duration: 197.735369ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:03.365405Z","caller":"traceutil/trace.go:171","msg":"trace[501300573] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"247.71636ms","start":"2026-05-10T04:03:03.117661Z","end":"2026-05-10T04:03:03.365377Z","steps":["trace[501300573] 'process raft request' (duration: 247.530566ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:03.393893Z","caller":"traceutil/trace.go:171","msg":"trace[425339267] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"259.538611ms","start":"2026-05-10T04:03:03.134313Z","end":"2026-05-10T04:03:03.393851Z","steps":["trace[425339267] 'process raft request' (duration: 259.330335ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:03.393984Z","caller":"traceutil/trace.go:171","msg":"trace[1367961104] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"259.397067ms","start":"2026-05-10T04:03:03.134523Z","end":"2026-05-10T04:03:03.39392Z","steps":["trace[1367961104] 'process raft request' (duration: 259.269993ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:03.394147Z","caller":"traceutil/trace.go:171","msg":"trace[1566280041] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"235.429039ms","start":"2026-05-10T04:03:03.158559Z","end":"2026-05-10T04:03:03.393988Z","steps":["trace[1566280041] 'process raft request' (duration: 235.320016ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:03.394297Z","caller":"traceutil/trace.go:171","msg":"trace[1577638596] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"229.316183ms","start":"2026-05-10T04:03:03.164972Z","end":"2026-05-10T04:03:03.394288Z","steps":["trace[1577638596] 'process raft request' (duration: 228.940002ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:03.394389Z","caller":"traceutil/trace.go:171","msg":"trace[1273181743] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"221.270076ms","start":"2026-05-10T04:03:03.173111Z","end":"2026-05-10T04:03:03.394381Z","steps":["trace[1273181743] 'process raft request' (duration: 220.870874ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:03.394257Z","caller":"traceutil/trace.go:171","msg":"trace[534822552] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"221.174412ms","start":"2026-05-10T04:03:03.173065Z","end":"2026-05-10T04:03:03.39424Z","steps":["trace[534822552] 'process raft request' (duration: 220.882475ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:03.394717Z","caller":"traceutil/trace.go:171","msg":"trace[197903945] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"215.824288ms","start":"2026-05-10T04:03:03.178869Z","end":"2026-05-10T04:03:03.394693Z","steps":["trace[197903945] 'process raft request' (duration: 215.312224ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:03.394712Z","caller":"traceutil/trace.go:171","msg":"trace[1170480682] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"216.112456ms","start":"2026-05-10T04:03:03.178577Z","end":"2026-05-10T04:03:03.394689Z","steps":["trace[1170480682] 'process raft request' (duration: 215.487419ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:03.394781Z","caller":"traceutil/trace.go:171","msg":"trace[1576875705] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"241.457302ms","start":"2026-05-10T04:03:03.153267Z","end":"2026-05-10T04:03:03.394724Z","steps":["trace[1576875705] 'process raft request' (duration: 240.578698ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:03.395196Z","caller":"traceutil/trace.go:171","msg":"trace[1431054574] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"186.596007ms","start":"2026-05-10T04:03:03.208589Z","end":"2026-05-10T04:03:03.395185Z","steps":["trace[1431054574] 'process raft request' (duration: 185.752184ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:03.92412Z","caller":"traceutil/trace.go:171","msg":"trace[806810906] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"102.857042ms","start":"2026-05-10T04:03:03.821238Z","end":"2026-05-10T04:03:03.924095Z","steps":["trace[806810906] 'process raft request' (duration: 96.49094ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:04.832208Z","caller":"traceutil/trace.go:171","msg":"trace[1036714055] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"145.080834ms","start":"2026-05-10T04:03:04.687069Z","end":"2026-05-10T04:03:04.83215Z","steps":["trace[1036714055] 'process raft request' (duration: 105.041861ms)","trace[1036714055] 'compare' (duration: 39.703584ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:05.018009Z","caller":"traceutil/trace.go:171","msg":"trace[1892277540] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"132.279798ms","start":"2026-05-10T04:03:04.885689Z","end":"2026-05-10T04:03:05.017969Z","steps":["trace[1892277540] 'process raft request' (duration: 121.679571ms)","trace[1892277540] 'compare' (duration: 10.35526ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:05.018996Z","caller":"traceutil/trace.go:171","msg":"trace[654518458] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"105.910955ms","start":"2026-05-10T04:03:04.913049Z","end":"2026-05-10T04:03:05.01896Z","steps":["trace[654518458] 'read index received' (duration: 94.352092ms)","trace[654518458] 'applied index is now lower than readState.Index' (duration: 11.558103ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:05.019059Z","caller":"traceutil/trace.go:171","msg":"trace[695534157] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"125.212227ms","start":"2026-05-10T04:03:04.893825Z","end":"2026-05-10T04:03:05.019037Z","steps":["trace[695534157] 'process raft request' (duration: 124.722924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:05.019258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.187472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-05-10T04:03:05.019296Z","caller":"traceutil/trace.go:171","msg":"trace[1011160617] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:589; }","duration":"106.274135ms","start":"2026-05-10T04:03:04.913009Z","end":"2026-05-10T04:03:05.019283Z","steps":["trace[1011160617] 'agreement among raft nodes before linearized reading' (duration: 106.073059ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:06.497599Z","caller":"traceutil/trace.go:171","msg":"trace[1713821114] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"371.991762ms","start":"2026-05-10T04:03:06.12557Z","end":"2026-05-10T04:03:06.497562Z","steps":["trace[1713821114] 'read index received' (duration: 371.52581ms)","trace[1713821114] 'applied index is now lower than readState.Index' (duration: 464.262µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:06.497841Z","caller":"traceutil/trace.go:171","msg":"trace[670424791] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"689.974753ms","start":"2026-05-10T04:03:05.807696Z","end":"2026-05-10T04:03:06.49767Z","steps":["trace[670424791] 'process raft request' (duration: 689.572603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:06.498123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.626741ms","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-10T04:03:06.498171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.12893ms","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-10T04:03:06.498169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.565168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-10T04:03:06.498234Z","caller":"traceutil/trace.go:171","msg":"trace[1725129915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"365.749864ms","start":"2026-05-10T04:03:06.132457Z","end":"2026-05-10T04:03:06.498207Z","steps":["trace[1725129915] 'agreement among raft nodes before linearized reading' (duration: 365.466127ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:06.498241Z","caller":"traceutil/trace.go:171","msg":"trace[983952724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"364.218031ms","start":"2026-05-10T04:03:06.134003Z","end":"2026-05-10T04:03:06.498221Z","steps":["trace[983952724] 'agreement among raft nodes before linearized reading' (duration: 363.918454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:06.498177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:05.807675Z","time spent":"690.350166ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:03:06.498286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:06.132439Z","time spent":"365.839236ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-10T04:03:06.498282Z","caller":"traceutil/trace.go:171","msg":"trace[1505861875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"372.742713ms","start":"2026-05-10T04:03:06.125521Z","end":"2026-05-10T04:03:06.498263Z","steps":["trace[1505861875] 'agreement among raft nodes before linearized reading' (duration: 372.243329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:06.498298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:06.133998Z","time spent":"364.288664ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-10T04:03:06.498731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:06.125497Z","time spent":"373.202956ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-10T04:03:06.791797Z","caller":"traceutil/trace.go:171","msg":"trace[341598887] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"288.574657ms","start":"2026-05-10T04:03:06.503174Z","end":"2026-05-10T04:03:06.791749Z","steps":["trace[341598887] 'process raft request' (duration: 244.068983ms)","trace[341598887] 'compare' (duration: 44.277418ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:06.793419Z","caller":"traceutil/trace.go:171","msg":"trace[19488094] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"289.707576ms","start":"2026-05-10T04:03:06.503653Z","end":"2026-05-10T04:03:06.793361Z","steps":["trace[19488094] 'process raft request' (duration: 289.49017ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:06.793535Z","caller":"traceutil/trace.go:171","msg":"trace[35208175] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"287.943489ms","start":"2026-05-10T04:03:06.505554Z","end":"2026-05-10T04:03:06.793498Z","steps":["trace[35208175] 'process raft request' (duration: 287.695252ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:06.79367Z","caller":"traceutil/trace.go:171","msg":"trace[1454836741] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"220.102165ms","start":"2026-05-10T04:03:06.573557Z","end":"2026-05-10T04:03:06.793659Z","steps":["trace[1454836741] 'process raft request' (duration: 219.730434ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:06.931075Z","caller":"traceutil/trace.go:171","msg":"trace[1678417062] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"103.032566ms","start":"2026-05-10T04:03:06.828007Z","end":"2026-05-10T04:03:06.931039Z","steps":["trace[1678417062] 'process raft request' (duration: 92.616355ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:11.037099Z","caller":"traceutil/trace.go:171","msg":"trace[1857291590] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"229.094697ms","start":"2026-05-10T04:03:10.80797Z","end":"2026-05-10T04:03:11.037065Z","steps":["trace[1857291590] 'read index received' (duration: 227.858213ms)","trace[1857291590] 'applied index is now lower than readState.Index' (duration: 1.235344ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:11.037252Z","caller":"traceutil/trace.go:171","msg":"trace[2112361093] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"353.567145ms","start":"2026-05-10T04:03:10.683633Z","end":"2026-05-10T04:03:11.0372Z","steps":["trace[2112361093] 'process raft request' (duration: 352.612319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:11.037391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.385525ms","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-10T04:03:11.037398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.625168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T04:03:11.037443Z","caller":"traceutil/trace.go:171","msg":"trace[435711278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"229.530269ms","start":"2026-05-10T04:03:10.8079Z","end":"2026-05-10T04:03:11.037431Z","steps":["trace[435711278] 'agreement among raft nodes before linearized reading' (duration: 229.323433ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:11.037459Z","caller":"traceutil/trace.go:171","msg":"trace[75583965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"227.70659ms","start":"2026-05-10T04:03:10.809734Z","end":"2026-05-10T04:03:11.037441Z","steps":["trace[75583965] 'agreement among raft nodes before linearized reading' (duration: 227.553126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:11.03759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:10.683601Z","time spent":"353.76786ms","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:03:11.037847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.803765ms","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-10T04:03:11.037976Z","caller":"traceutil/trace.go:171","msg":"trace[1079950004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"227.036402ms","start":"2026-05-10T04:03:10.810907Z","end":"2026-05-10T04:03:11.037944Z","steps":["trace[1079950004] 'agreement among raft nodes before linearized reading' (duration: 226.528408ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:11.773913Z","caller":"traceutil/trace.go:171","msg":"trace[2089006688] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"207.931754ms","start":"2026-05-10T04:03:11.565952Z","end":"2026-05-10T04:03:11.773884Z","steps":["trace[2089006688] 'process raft request' (duration: 206.976378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:12.753255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.122581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:03:12.753611Z","caller":"traceutil/trace.go:171","msg":"trace[303507504] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"130.411188ms","start":"2026-05-10T04:03:12.62317Z","end":"2026-05-10T04:03:12.753582Z","steps":["trace[303507504] 'process raft request' (duration: 130.335876ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:12.753618Z","caller":"traceutil/trace.go:171","msg":"trace[1606997008] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"963.712959ms","start":"2026-05-10T04:03:11.78985Z","end":"2026-05-10T04:03:12.753563Z","steps":["trace[1606997008] 'process raft request' (duration: 534.992422ms)","trace[1606997008] 'compare' (duration: 427.262398ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:12.753713Z","caller":"traceutil/trace.go:171","msg":"trace[1845156522] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"917.758246ms","start":"2026-05-10T04:03:11.835934Z","end":"2026-05-10T04:03:12.753693Z","steps":["trace[1845156522] 'process raft request' (duration: 917.466708ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:12.753718Z","caller":"traceutil/trace.go:171","msg":"trace[2058340914] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"210.757591ms","start":"2026-05-10T04:03:12.542896Z","end":"2026-05-10T04:03:12.753653Z","steps":["trace[2058340914] 'read index received' (duration: 120.163491ms)","trace[2058340914] 'applied index is now lower than readState.Index' (duration: 90.59081ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:12.753805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:11.835852Z","time spent":"917.906759ms","remote":"127.0.0.1:53714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T04:03:12.753872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.985007ms","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-10T04:03:12.753882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:11.789813Z","time spent":"963.919115ms","remote":"127.0.0.1:53660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:03:12.753906Z","caller":"traceutil/trace.go:171","msg":"trace[1787756712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"211.01697ms","start":"2026-05-10T04:03:12.542875Z","end":"2026-05-10T04:03:12.753892Z","steps":["trace[1787756712] 'agreement among raft nodes before linearized reading' (duration: 210.903475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:14.524134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.638169003s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:03:14.524255Z","caller":"traceutil/trace.go:171","msg":"trace[185508108] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"1.473447958s","start":"2026-05-10T04:03:13.050787Z","end":"2026-05-10T04:03:14.524235Z","steps":["trace[185508108] 'read index received' (duration: 64.823µs)","trace[185508108] 'applied index is now lower than readState.Index' (duration: 1.473382005s)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:03:14.524356Z","caller":"traceutil/trace.go:171","msg":"trace[149193673] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.713658375s","start":"2026-05-10T04:03:12.810682Z","end":"2026-05-10T04:03:14.52434Z","steps":["trace[149193673] 'process raft request' (duration: 75.203074ms)","trace[149193673] 'compare' (duration: 1.637858105s)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:14.524405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:12.810669Z","time spent":"1.713711116s","remote":"127.0.0.1:53660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T04:03:14.524716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.473923141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T04:03:14.524746Z","caller":"traceutil/trace.go:171","msg":"trace[37668166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"1.474011333s","start":"2026-05-10T04:03:13.050725Z","end":"2026-05-10T04:03:14.524736Z","steps":["trace[37668166] 'agreement among raft nodes before linearized reading' (duration: 1.473857059s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:14.524811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:13.050693Z","time spent":"1.474110805s","remote":"127.0.0.1:53740","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-10T04:03:14.525157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.17086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-10T04:03:14.525188Z","caller":"traceutil/trace.go:171","msg":"trace[648771770] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:629; }","duration":"680.20243ms","start":"2026-05-10T04:03:13.844976Z","end":"2026-05-10T04:03:14.525178Z","steps":["trace[648771770] 'agreement among raft nodes before linearized reading' (duration: 680.109097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:14.525215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:13.844915Z","time spent":"680.294613ms","remote":"127.0.0.1:53506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"warn","ts":"2026-05-10T04:03:14.525499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.324098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T04:03:14.525572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.473008515s","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-10T04:03:14.525612Z","caller":"traceutil/trace.go:171","msg":"trace[1760238825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"790.469402ms","start":"2026-05-10T04:03:13.735109Z","end":"2026-05-10T04:03:14.525579Z","steps":["trace[1760238825] 'agreement among raft nodes before linearized reading' (duration: 790.224615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:14.525592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474732302s","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-10T04:03:14.52564Z","caller":"traceutil/trace.go:171","msg":"trace[1598238522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"1.473115469s","start":"2026-05-10T04:03:13.052504Z","end":"2026-05-10T04:03:14.52562Z","steps":["trace[1598238522] 'agreement among raft nodes before linearized reading' (duration: 1.472831181s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:14.525675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:13.735086Z","time spent":"790.576716ms","remote":"127.0.0.1:53458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T04:03:14.525697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:13.052496Z","time spent":"1.473188501s","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-10T04:03:14.525693Z","caller":"traceutil/trace.go:171","msg":"trace[1303952466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"1.474895607s","start":"2026-05-10T04:03:13.050776Z","end":"2026-05-10T04:03:14.525671Z","steps":["trace[1303952466] 'agreement among raft nodes before linearized reading' (duration: 1.474434255s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:14.525771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:13.050758Z","time spent":"1.475002089s","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-10T04:03:15.884243Z","caller":"traceutil/trace.go:171","msg":"trace[1575296167] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"107.274982ms","start":"2026-05-10T04:03:15.776934Z","end":"2026-05-10T04:03:15.884209Z","steps":["trace[1575296167] 'process raft request' (duration: 107.105448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:19.16384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.167328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-05-10T04:03:19.163957Z","caller":"traceutil/trace.go:171","msg":"trace[719267858] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:706; }","duration":"192.364114ms","start":"2026-05-10T04:03:18.971554Z","end":"2026-05-10T04:03:19.163919Z","steps":["trace[719267858] 'range keys from in-memory index tree' (duration: 191.913281ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:26.7743Z","caller":"traceutil/trace.go:171","msg":"trace[1856475647] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:791; }","duration":"145.827335ms","start":"2026-05-10T04:03:26.628444Z","end":"2026-05-10T04:03:26.774271Z","steps":["trace[1856475647] 'read index received' (duration: 37.847384ms)","trace[1856475647] 'applied index is now lower than readState.Index' (duration: 107.978411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:03:26.774594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.121342ms","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-10T04:03:26.774682Z","caller":"traceutil/trace.go:171","msg":"trace[1392299790] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"146.234385ms","start":"2026-05-10T04:03:26.628436Z","end":"2026-05-10T04:03:26.77467Z","steps":["trace[1392299790] 'agreement among raft nodes before linearized reading' (duration: 145.976879ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:26.774652Z","caller":"traceutil/trace.go:171","msg":"trace[1304389550] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"145.195167ms","start":"2026-05-10T04:03:26.629145Z","end":"2026-05-10T04:03:26.77434Z","steps":["trace[1304389550] 'process raft request' (duration: 145.070404ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:26.774977Z","caller":"traceutil/trace.go:171","msg":"trace[1610873903] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"146.40531ms","start":"2026-05-10T04:03:26.627916Z","end":"2026-05-10T04:03:26.774322Z","steps":["trace[1610873903] 'process raft request' (duration: 140.582603ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:03:27.41658Z","caller":"traceutil/trace.go:171","msg":"trace[1318117647] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"109.067011ms","start":"2026-05-10T04:03:27.307476Z","end":"2026-05-10T04:03:27.416543Z","steps":["trace[1318117647] 'process raft request' (duration: 108.863345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:30.51324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.748904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-85mbs\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-10T04:03:30.513332Z","caller":"traceutil/trace.go:171","msg":"trace[477080882] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-85mbs; range_end:; response_count:1; response_revision:790; }","duration":"650.848276ms","start":"2026-05-10T04:03:29.862458Z","end":"2026-05-10T04:03:30.513306Z","steps":["trace[477080882] 'range keys from in-memory index tree' (duration: 650.555188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:30.513373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:29.86244Z","time spent":"650.923707ms","remote":"127.0.0.1:53660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-85mbs\" "} {"level":"warn","ts":"2026-05-10T04:03:30.513552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.873354ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:03:30.513624Z","caller":"traceutil/trace.go:171","msg":"trace[968455085] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:790; }","duration":"631.949335ms","start":"2026-05-10T04:03:29.881666Z","end":"2026-05-10T04:03:30.513616Z","steps":["trace[968455085] 'range keys from in-memory index tree' (duration: 631.864692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:03:30.515745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.045653ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-10T04:03:49.079006Z","caller":"traceutil/trace.go:171","msg":"trace[1404813193] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"186.187355ms","start":"2026-05-10T04:03:48.892755Z","end":"2026-05-10T04:03:49.078943Z","steps":["trace[1404813193] 'process raft request' (duration: 88.92656ms)","trace[1404813193] 'compare' (duration: 97.157452ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:04:00.234613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970380026256413,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-10T04:04:00.304972Z","caller":"traceutil/trace.go:171","msg":"trace[895979352] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"571.36132ms","start":"2026-05-10T04:03:59.733586Z","end":"2026-05-10T04:04:00.304948Z","steps":["trace[895979352] 'read index received' (duration: 571.151244ms)","trace[895979352] 'applied index is now lower than readState.Index' (duration: 208.935µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:04:00.305021Z","caller":"traceutil/trace.go:171","msg":"trace[966291106] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"695.368638ms","start":"2026-05-10T04:03:59.60962Z","end":"2026-05-10T04:04:00.304989Z","steps":["trace[966291106] 'process raft request' (duration: 695.124132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:00.305085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.490753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:04:00.305117Z","caller":"traceutil/trace.go:171","msg":"trace[50002857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"571.585274ms","start":"2026-05-10T04:03:59.733522Z","end":"2026-05-10T04:04:00.305107Z","steps":["trace[50002857] 'agreement among raft nodes before linearized reading' (duration: 571.519283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:00.305166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:59.609594Z","time spent":"695.491552ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:04:00.305187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:03:59.733498Z","time spent":"571.678998ms","remote":"127.0.0.1:53458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T04:04:02.425411Z","caller":"traceutil/trace.go:171","msg":"trace[1494462142] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"115.563641ms","start":"2026-05-10T04:04:02.309791Z","end":"2026-05-10T04:04:02.425355Z","steps":["trace[1494462142] 'process raft request' (duration: 115.353075ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:03.458881Z","caller":"traceutil/trace.go:171","msg":"trace[858042321] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"121.849571ms","start":"2026-05-10T04:04:03.336988Z","end":"2026-05-10T04:04:03.458838Z","steps":["trace[858042321] 'process raft request' (duration: 79.457713ms)","trace[858042321] 'compare' (duration: 42.320637ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:04:03.534868Z","caller":"traceutil/trace.go:171","msg":"trace[1437543577] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"160.484438ms","start":"2026-05-10T04:04:03.374359Z","end":"2026-05-10T04:04:03.534843Z","steps":["trace[1437543577] 'process raft request' (duration: 160.441977ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:03.534891Z","caller":"traceutil/trace.go:171","msg":"trace[1943098975] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"196.465003ms","start":"2026-05-10T04:04:03.338406Z","end":"2026-05-10T04:04:03.534871Z","steps":["trace[1943098975] 'process raft request' (duration: 196.262357ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:04.133417Z","caller":"traceutil/trace.go:171","msg":"trace[681942454] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"170.775577ms","start":"2026-05-10T04:04:03.962609Z","end":"2026-05-10T04:04:04.133384Z","steps":["trace[681942454] 'process raft request' (duration: 82.302629ms)","trace[681942454] 'compare' (duration: 88.328733ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:04:04.931721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970380026256468,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-10T04:04:05.312529Z","caller":"traceutil/trace.go:171","msg":"trace[1799894966] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"881.81765ms","start":"2026-05-10T04:04:04.43069Z","end":"2026-05-10T04:04:05.312508Z","steps":["trace[1799894966] 'read index received' (duration: 881.625075ms)","trace[1799894966] 'applied index is now lower than readState.Index' (duration: 191.785µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:04:05.312624Z","caller":"traceutil/trace.go:171","msg":"trace[213577654] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"882.025955ms","start":"2026-05-10T04:04:04.430566Z","end":"2026-05-10T04:04:05.312592Z","steps":["trace[213577654] 'process raft request' (duration: 881.804249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:05.312679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.961274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:04:05.312715Z","caller":"traceutil/trace.go:171","msg":"trace[203853655] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1002; }","duration":"882.019335ms","start":"2026-05-10T04:04:04.430685Z","end":"2026-05-10T04:04:05.312704Z","steps":["trace[203853655] 'agreement among raft nodes before linearized reading' (duration: 881.938524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:05.312744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:04.43064Z","time spent":"882.096218ms","remote":"127.0.0.1:53750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-05-10T04:04:05.312813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:04.43054Z","time spent":"882.150339ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:04:05.313082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.428071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:04:05.313178Z","caller":"traceutil/trace.go:171","msg":"trace[1676346054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"578.620736ms","start":"2026-05-10T04:04:04.734536Z","end":"2026-05-10T04:04:05.313157Z","steps":["trace[1676346054] 'agreement among raft nodes before linearized reading' (duration: 578.42248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:05.313222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:04.734504Z","time spent":"578.711567ms","remote":"127.0.0.1:53458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T04:04:05.313263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.54447ms","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-10T04:04:05.313362Z","caller":"traceutil/trace.go:171","msg":"trace[44823308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"376.701175ms","start":"2026-05-10T04:04:04.936638Z","end":"2026-05-10T04:04:05.313339Z","steps":["trace[44823308] 'agreement among raft nodes before linearized reading' (duration: 376.432918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:05.31341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:04.936607Z","time spent":"376.793947ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T04:04:05.68536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.690952ms","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-10T04:04:05.685442Z","caller":"traceutil/trace.go:171","msg":"trace[46461572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1005; }","duration":"222.841396ms","start":"2026-05-10T04:04:05.462582Z","end":"2026-05-10T04:04:05.685424Z","steps":["trace[46461572] 'range keys from in-memory index tree' (duration: 222.511478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:05.685436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.413726ms","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-10T04:04:05.685489Z","caller":"traceutil/trace.go:171","msg":"trace[305301771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1005; }","duration":"146.52986ms","start":"2026-05-10T04:04:05.538946Z","end":"2026-05-10T04:04:05.685476Z","steps":["trace[305301771] 'range keys from in-memory index tree' (duration: 146.19498ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:07.855826Z","caller":"traceutil/trace.go:171","msg":"trace[896148734] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"488.629387ms","start":"2026-05-10T04:04:07.367172Z","end":"2026-05-10T04:04:07.855801Z","steps":["trace[896148734] 'read index received' (duration: 488.465193ms)","trace[896148734] 'applied index is now lower than readState.Index' (duration: 163.364µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:04:07.855862Z","caller":"traceutil/trace.go:171","msg":"trace[1437226780] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"537.163282ms","start":"2026-05-10T04:04:07.31867Z","end":"2026-05-10T04:04:07.855833Z","steps":["trace[1437226780] 'process raft request' (duration: 536.965227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:07.856042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.852454ms","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-10T04:04:07.856028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:07.318646Z","time spent":"537.297886ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:04:07.856082Z","caller":"traceutil/trace.go:171","msg":"trace[801611523] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1008; }","duration":"488.909236ms","start":"2026-05-10T04:04:07.367163Z","end":"2026-05-10T04:04:07.856072Z","steps":["trace[801611523] 'agreement among raft nodes before linearized reading' (duration: 488.760371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:07.856128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:07.367088Z","time spent":"489.032379ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-10T04:04:07.856144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.905565ms","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-10T04:04:07.856199Z","caller":"traceutil/trace.go:171","msg":"trace[1054669526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"488.991578ms","start":"2026-05-10T04:04:07.367192Z","end":"2026-05-10T04:04:07.856184Z","steps":["trace[1054669526] 'agreement among raft nodes before linearized reading' (duration: 488.854084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:07.856227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:07.367181Z","time spent":"489.040398ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T04:04:07.856276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.815771ms","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-10T04:04:07.856301Z","caller":"traceutil/trace.go:171","msg":"trace[1483277290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"155.873053ms","start":"2026-05-10T04:04:07.700421Z","end":"2026-05-10T04:04:07.856294Z","steps":["trace[1483277290] 'agreement among raft nodes before linearized reading' (duration: 155.80232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:07.856328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.822195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:04:07.856372Z","caller":"traceutil/trace.go:171","msg":"trace[599358941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"123.904268ms","start":"2026-05-10T04:04:07.732455Z","end":"2026-05-10T04:04:07.85636Z","steps":["trace[599358941] 'agreement among raft nodes before linearized reading' (duration: 123.822535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:07.856398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.396067ms","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-10T04:04:07.856432Z","caller":"traceutil/trace.go:171","msg":"trace[1194111219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1008; }","duration":"142.45209ms","start":"2026-05-10T04:04:07.713966Z","end":"2026-05-10T04:04:07.856418Z","steps":["trace[1194111219] 'agreement among raft nodes before linearized reading' (duration: 142.386378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:07.856441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.919768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T04:04:07.856473Z","caller":"traceutil/trace.go:171","msg":"trace[1980082570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1008; }","duration":"480.995191ms","start":"2026-05-10T04:04:07.375469Z","end":"2026-05-10T04:04:07.856464Z","steps":["trace[1980082570] 'agreement among raft nodes before linearized reading' (duration: 480.877588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:07.856493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:07.375451Z","time spent":"481.037262ms","remote":"127.0.0.1:53740","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-10T04:04:07.856735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.213073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T04:04:07.856796Z","caller":"traceutil/trace.go:171","msg":"trace[262432049] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1008; }","duration":"348.717067ms","start":"2026-05-10T04:04:07.508072Z","end":"2026-05-10T04:04:07.856789Z","steps":["trace[262432049] 'agreement among raft nodes before linearized reading' (duration: 348.224835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:07.856823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:07.508054Z","time spent":"348.759149ms","remote":"127.0.0.1:53908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-05-10T04:04:10.337477Z","caller":"traceutil/trace.go:171","msg":"trace[143744321] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1055; }","duration":"154.902588ms","start":"2026-05-10T04:04:10.182559Z","end":"2026-05-10T04:04:10.337461Z","steps":["trace[143744321] 'read index received' (duration: 143.306153ms)","trace[143744321] 'applied index is now lower than readState.Index' (duration: 11.595805ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:04:10.337571Z","caller":"traceutil/trace.go:171","msg":"trace[1660062262] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"374.842345ms","start":"2026-05-10T04:04:09.962701Z","end":"2026-05-10T04:04:10.337544Z","steps":["trace[1660062262] 'process raft request' (duration: 363.20947ms)","trace[1660062262] 'compare' (duration: 11.015128ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:04:10.337595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.01298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:04:10.337622Z","caller":"traceutil/trace.go:171","msg":"trace[215249599] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1020; }","duration":"155.096531ms","start":"2026-05-10T04:04:10.182517Z","end":"2026-05-10T04:04:10.337614Z","steps":["trace[215249599] 'agreement among raft nodes before linearized reading' (duration: 155.008019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:10.337685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:09.962671Z","time spent":"374.949887ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:04:10.337761Z","caller":"traceutil/trace.go:171","msg":"trace[1869579424] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"374.368852ms","start":"2026-05-10T04:04:09.963385Z","end":"2026-05-10T04:04:10.337754Z","steps":["trace[1869579424] 'process raft request' (duration: 373.999652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:10.337822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:09.963368Z","time spent":"374.412593ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:04:10.337963Z","caller":"traceutil/trace.go:171","msg":"trace[367889714] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"374.231418ms","start":"2026-05-10T04:04:09.963725Z","end":"2026-05-10T04:04:10.337956Z","steps":["trace[367889714] 'process raft request' (duration: 373.690344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:10.337997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:09.963703Z","time spent":"374.27503ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:04:10.338057Z","caller":"traceutil/trace.go:171","msg":"trace[1757099978] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"374.122145ms","start":"2026-05-10T04:04:09.963881Z","end":"2026-05-10T04:04:10.338003Z","steps":["trace[1757099978] 'process raft request' (duration: 373.55628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:10.338176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:09.963869Z","time spent":"374.232328ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:04:22.652505Z","caller":"traceutil/trace.go:171","msg":"trace[1398854491] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1096; }","duration":"238.635135ms","start":"2026-05-10T04:04:22.413839Z","end":"2026-05-10T04:04:22.652474Z","steps":["trace[1398854491] 'read index received' (duration: 39.399977ms)","trace[1398854491] 'applied index is now lower than readState.Index' (duration: 199.233608ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:04:22.652684Z","caller":"traceutil/trace.go:171","msg":"trace[667574149] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"240.898906ms","start":"2026-05-10T04:04:22.411767Z","end":"2026-05-10T04:04:22.652666Z","steps":["trace[667574149] 'process raft request' (duration: 240.534366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:22.652965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.453685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-10T04:04:22.653014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.157909ms","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-10T04:04:22.653066Z","caller":"traceutil/trace.go:171","msg":"trace[2010109238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1057; }","duration":"233.573748ms","start":"2026-05-10T04:04:22.419464Z","end":"2026-05-10T04:04:22.653038Z","steps":["trace[2010109238] 'agreement among raft nodes before linearized reading' (duration: 233.316411ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:22.653081Z","caller":"traceutil/trace.go:171","msg":"trace[204486530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"239.260581ms","start":"2026-05-10T04:04:22.413805Z","end":"2026-05-10T04:04:22.653066Z","steps":["trace[204486530] 'agreement among raft nodes before linearized reading' (duration: 239.071437ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:28.340858Z","caller":"traceutil/trace.go:171","msg":"trace[825621245] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"165.843103ms","start":"2026-05-10T04:04:28.174991Z","end":"2026-05-10T04:04:28.340834Z","steps":["trace[825621245] 'process raft request' (duration: 165.114093ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:32.472034Z","caller":"traceutil/trace.go:171","msg":"trace[1190804578] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"117.110482ms","start":"2026-05-10T04:04:32.354899Z","end":"2026-05-10T04:04:32.472009Z","steps":["trace[1190804578] 'read index received' (duration: 116.827875ms)","trace[1190804578] 'applied index is now lower than readState.Index' (duration: 281.567µs)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:04:32.472348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.363648ms","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-10T04:04:32.472422Z","caller":"traceutil/trace.go:171","msg":"trace[70456494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"117.603355ms","start":"2026-05-10T04:04:32.354806Z","end":"2026-05-10T04:04:32.472409Z","steps":["trace[70456494] 'agreement among raft nodes before linearized reading' (duration: 117.303606ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:32.472562Z","caller":"traceutil/trace.go:171","msg":"trace[227842657] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"290.759036ms","start":"2026-05-10T04:04:32.181752Z","end":"2026-05-10T04:04:32.472511Z","steps":["trace[227842657] 'process raft request' (duration: 290.085458ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:34.281126Z","caller":"traceutil/trace.go:171","msg":"trace[1439325122] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"312.808754ms","start":"2026-05-10T04:04:33.968272Z","end":"2026-05-10T04:04:34.281081Z","steps":["trace[1439325122] 'process raft request' (duration: 302.512255ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:34.281209Z","caller":"traceutil/trace.go:171","msg":"trace[479353526] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"170.869158ms","start":"2026-05-10T04:04:34.110307Z","end":"2026-05-10T04:04:34.281177Z","steps":["trace[479353526] 'process raft request' (duration: 170.652913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:34.281355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:33.968238Z","time spent":"313.008401ms","remote":"127.0.0.1:53506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:04:34.619874Z","caller":"traceutil/trace.go:171","msg":"trace[1314712629] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"140.062415ms","start":"2026-05-10T04:04:34.47978Z","end":"2026-05-10T04:04:34.619842Z","steps":["trace[1314712629] 'process raft request' (duration: 127.107175ms)","trace[1314712629] 'compare' (duration: 12.726784ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:04:34.736082Z","caller":"traceutil/trace.go:171","msg":"trace[237283326] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"110.707289ms","start":"2026-05-10T04:04:34.625348Z","end":"2026-05-10T04:04:34.736056Z","steps":["trace[237283326] 'process raft request' (duration: 98.18307ms)","trace[237283326] 'compare' (duration: 12.019485ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:04:36.966865Z","caller":"traceutil/trace.go:171","msg":"trace[1283321729] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"232.039077ms","start":"2026-05-10T04:04:36.734792Z","end":"2026-05-10T04:04:36.966831Z","steps":["trace[1283321729] 'read index received' (duration: 185.427564ms)","trace[1283321729] 'applied index is now lower than readState.Index' (duration: 46.610003ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:04:36.966959Z","caller":"traceutil/trace.go:171","msg":"trace[504822978] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"268.645289ms","start":"2026-05-10T04:04:36.698275Z","end":"2026-05-10T04:04:36.96692Z","steps":["trace[504822978] 'process raft request' (duration: 222.010156ms)","trace[504822978] 'compare' (duration: 46.374745ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:04:36.967034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.229691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:04:36.967135Z","caller":"traceutil/trace.go:171","msg":"trace[1608112832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1102; }","duration":"232.397936ms","start":"2026-05-10T04:04:36.734707Z","end":"2026-05-10T04:04:36.967105Z","steps":["trace[1608112832] 'agreement among raft nodes before linearized reading' (duration: 232.241632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:36.967191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.410294ms","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-10T04:04:36.96726Z","caller":"traceutil/trace.go:171","msg":"trace[1326450247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1102; }","duration":"226.542169ms","start":"2026-05-10T04:04:36.740699Z","end":"2026-05-10T04:04:36.967241Z","steps":["trace[1326450247] 'agreement among raft nodes before linearized reading' (duration: 226.340633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:36.967253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.490713ms","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-10T04:04:36.967192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.936491ms","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-10T04:04:36.967349Z","caller":"traceutil/trace.go:171","msg":"trace[1952039976] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1102; }","duration":"134.634267ms","start":"2026-05-10T04:04:36.83269Z","end":"2026-05-10T04:04:36.967324Z","steps":["trace[1952039976] 'agreement among raft nodes before linearized reading' (duration: 134.36382ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:36.967402Z","caller":"traceutil/trace.go:171","msg":"trace[824078076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"164.198019ms","start":"2026-05-10T04:04:36.80319Z","end":"2026-05-10T04:04:36.967388Z","steps":["trace[824078076] 'agreement among raft nodes before linearized reading' (duration: 163.879231ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:37.157084Z","caller":"traceutil/trace.go:171","msg":"trace[505533418] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"185.097095ms","start":"2026-05-10T04:04:36.971954Z","end":"2026-05-10T04:04:37.157051Z","steps":["trace[505533418] 'process raft request' (duration: 184.983742ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:37.157187Z","caller":"traceutil/trace.go:171","msg":"trace[323044047] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"185.417974ms","start":"2026-05-10T04:04:36.971733Z","end":"2026-05-10T04:04:37.157151Z","steps":["trace[323044047] 'process raft request' (duration: 184.933301ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:37.157337Z","caller":"traceutil/trace.go:171","msg":"trace[572545789] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"184.903549ms","start":"2026-05-10T04:04:36.972403Z","end":"2026-05-10T04:04:37.157307Z","steps":["trace[572545789] 'process raft request' (duration: 184.598691ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:51.255567Z","caller":"traceutil/trace.go:171","msg":"trace[1767781597] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1247; }","duration":"210.143923ms","start":"2026-05-10T04:04:51.045405Z","end":"2026-05-10T04:04:51.255549Z","steps":["trace[1767781597] 'read index received' (duration: 209.956518ms)","trace[1767781597] 'applied index is now lower than readState.Index' (duration: 186.655µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:04:51.255658Z","caller":"traceutil/trace.go:171","msg":"trace[238891672] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"512.421261ms","start":"2026-05-10T04:04:50.743229Z","end":"2026-05-10T04:04:51.25565Z","steps":["trace[238891672] 'process raft request' (duration: 512.189574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:51.255747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:04:50.743203Z","time spent":"512.472454ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:04:51.25592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.523163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-10T04:04:51.255941Z","caller":"traceutil/trace.go:171","msg":"trace[415242746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1199; }","duration":"210.580824ms","start":"2026-05-10T04:04:51.045354Z","end":"2026-05-10T04:04:51.255935Z","steps":["trace[415242746] 'agreement among raft nodes before linearized reading' (duration: 210.486992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:04:51.256033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.541119ms","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-10T04:04:51.256051Z","caller":"traceutil/trace.go:171","msg":"trace[1020552371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1199; }","duration":"143.62922ms","start":"2026-05-10T04:04:51.112414Z","end":"2026-05-10T04:04:51.256043Z","steps":["trace[1020552371] 'agreement among raft nodes before linearized reading' (duration: 143.583429ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:51.364351Z","caller":"traceutil/trace.go:171","msg":"trace[1803497085] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"103.31409ms","start":"2026-05-10T04:04:51.261005Z","end":"2026-05-10T04:04:51.364319Z","steps":["trace[1803497085] 'process raft request' (duration: 103.195637ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:51.3646Z","caller":"traceutil/trace.go:171","msg":"trace[1320296520] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"103.556375ms","start":"2026-05-10T04:04:51.261033Z","end":"2026-05-10T04:04:51.364589Z","steps":["trace[1320296520] 'process raft request' (duration: 102.874626ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:51.364992Z","caller":"traceutil/trace.go:171","msg":"trace[2097418561] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"102.592669ms","start":"2026-05-10T04:04:51.262382Z","end":"2026-05-10T04:04:51.364974Z","steps":["trace[2097418561] 'process raft request' (duration: 101.88146ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:04:51.364998Z","caller":"traceutil/trace.go:171","msg":"trace[1659656074] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"102.505037ms","start":"2026-05-10T04:04:51.262459Z","end":"2026-05-10T04:04:51.364964Z","steps":["trace[1659656074] 'process raft request' (duration: 102.290431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:03.598655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.111847ms","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-10T04:05:03.598655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.037246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T04:05:03.59876Z","caller":"traceutil/trace.go:171","msg":"trace[390420326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1239; }","duration":"146.1758ms","start":"2026-05-10T04:05:03.452571Z","end":"2026-05-10T04:05:03.598747Z","steps":["trace[390420326] 'range keys from in-memory index tree' (duration: 145.951843ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:03.59876Z","caller":"traceutil/trace.go:171","msg":"trace[495282756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1239; }","duration":"146.251943ms","start":"2026-05-10T04:05:03.452489Z","end":"2026-05-10T04:05:03.598741Z","steps":["trace[495282756] 'range keys from in-memory index tree' (duration: 145.871341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:03.598771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.686402ms","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-10T04:05:03.598797Z","caller":"traceutil/trace.go:171","msg":"trace[1397378173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1239; }","duration":"140.753033ms","start":"2026-05-10T04:05:03.458038Z","end":"2026-05-10T04:05:03.598791Z","steps":["trace[1397378173] 'range keys from in-memory index tree' (duration: 140.563228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:03.59894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.858024ms","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-10T04:05:03.599055Z","caller":"traceutil/trace.go:171","msg":"trace[307144144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"183.045459ms","start":"2026-05-10T04:05:03.415982Z","end":"2026-05-10T04:05:03.599027Z","steps":["trace[307144144] 'range keys from in-memory index tree' (duration: 182.622076ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:09.986404Z","caller":"traceutil/trace.go:171","msg":"trace[390112711] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"263.368365ms","start":"2026-05-10T04:05:09.722999Z","end":"2026-05-10T04:05:09.986367Z","steps":["trace[390112711] 'process raft request' (duration: 263.242842ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:09.986416Z","caller":"traceutil/trace.go:171","msg":"trace[103220702] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"263.397886ms","start":"2026-05-10T04:05:09.722972Z","end":"2026-05-10T04:05:09.986369Z","steps":["trace[103220702] 'process raft request' (duration: 263.043925ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:09.986648Z","caller":"traceutil/trace.go:171","msg":"trace[394797147] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"250.091065ms","start":"2026-05-10T04:05:09.736532Z","end":"2026-05-10T04:05:09.986623Z","steps":["trace[394797147] 'process raft request' (duration: 249.772016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:09.986676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.210454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:05:09.986567Z","caller":"traceutil/trace.go:171","msg":"trace[1976006494] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1328; }","duration":"251.117892ms","start":"2026-05-10T04:05:09.735436Z","end":"2026-05-10T04:05:09.986554Z","steps":["trace[1976006494] 'read index received' (duration: 10.287088ms)","trace[1976006494] 'applied index is now lower than readState.Index' (duration: 240.828604ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:05:09.98672Z","caller":"traceutil/trace.go:171","msg":"trace[947555052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1279; }","duration":"251.323239ms","start":"2026-05-10T04:05:09.735387Z","end":"2026-05-10T04:05:09.98671Z","steps":["trace[947555052] 'agreement among raft nodes before linearized reading' (duration: 251.206175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:09.986915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.844467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5514"} {"level":"info","ts":"2026-05-10T04:05:09.986986Z","caller":"traceutil/trace.go:171","msg":"trace[878014972] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1279; }","duration":"205.964759ms","start":"2026-05-10T04:05:09.781003Z","end":"2026-05-10T04:05:09.986967Z","steps":["trace[878014972] 'agreement among raft nodes before linearized reading' (duration: 205.784265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:10.585247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.345324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:05:10.585372Z","caller":"traceutil/trace.go:171","msg":"trace[23219654] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"348.506371ms","start":"2026-05-10T04:05:10.236845Z","end":"2026-05-10T04:05:10.585351Z","steps":["trace[23219654] 'read index received' (duration: 201.826417ms)","trace[23219654] 'applied index is now lower than readState.Index' (duration: 146.677954ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:05:10.585435Z","caller":"traceutil/trace.go:171","msg":"trace[1658826790] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"480.795565ms","start":"2026-05-10T04:05:10.104599Z","end":"2026-05-10T04:05:10.585394Z","steps":["trace[1658826790] 'process raft request' (duration: 334.189833ms)","trace[1658826790] 'compare' (duration: 146.217181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:05:10.58554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.693426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-zspph\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-05-10T04:05:10.58557Z","caller":"traceutil/trace.go:171","msg":"trace[657638339] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-zspph; range_end:; response_count:1; response_revision:1281; }","duration":"348.780547ms","start":"2026-05-10T04:05:10.236777Z","end":"2026-05-10T04:05:10.585558Z","steps":["trace[657638339] 'agreement among raft nodes before linearized reading' (duration: 348.625334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:10.585606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:10.236749Z","time spent":"348.84823ms","remote":"127.0.0.1:53660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11083,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-zspph\" "} {"level":"warn","ts":"2026-05-10T04:05:10.585611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:10.104569Z","time spent":"480.943229ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:05:11.12354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.72846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:05:11.123679Z","caller":"traceutil/trace.go:171","msg":"trace[1075019337] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"524.686114ms","start":"2026-05-10T04:05:10.598967Z","end":"2026-05-10T04:05:11.123653Z","steps":["trace[1075019337] 'process raft request' (duration: 403.652505ms)","trace[1075019337] 'compare' (duration: 120.153455ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:05:11.123851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:10.598899Z","time spent":"524.880379ms","remote":"127.0.0.1:53660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:05:11.308521Z","caller":"traceutil/trace.go:171","msg":"trace[1521793093] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"175.759991ms","start":"2026-05-10T04:05:11.132728Z","end":"2026-05-10T04:05:11.308488Z","steps":["trace[1521793093] 'process raft request' (duration: 173.269574ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:11.308888Z","caller":"traceutil/trace.go:171","msg":"trace[1993775749] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"171.449765ms","start":"2026-05-10T04:05:11.137395Z","end":"2026-05-10T04:05:11.308844Z","steps":["trace[1993775749] 'process raft request' (duration: 171.021743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:11.726799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.800166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:05:11.727159Z","caller":"traceutil/trace.go:171","msg":"trace[1394290746] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"330.092652ms","start":"2026-05-10T04:05:11.396994Z","end":"2026-05-10T04:05:11.727087Z","steps":["trace[1394290746] 'process raft request' (duration: 209.920626ms)","trace[1394290746] 'compare' (duration: 119.700964ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:05:11.727698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:11.396979Z","time spent":"330.570374ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:05:12.506433Z","caller":"traceutil/trace.go:171","msg":"trace[1784027464] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"292.764671ms","start":"2026-05-10T04:05:12.213639Z","end":"2026-05-10T04:05:12.506404Z","steps":["trace[1784027464] 'read index received' (duration: 239.59409ms)","trace[1784027464] 'applied index is now lower than readState.Index' (duration: 53.1691ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:05:12.50647Z","caller":"traceutil/trace.go:171","msg":"trace[1826034563] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"510.207441ms","start":"2026-05-10T04:05:11.996207Z","end":"2026-05-10T04:05:12.506415Z","steps":["trace[1826034563] 'process raft request' (duration: 456.976559ms)","trace[1826034563] 'compare' (duration: 52.555623ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:05:12.506575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.916145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:05:12.5066Z","caller":"traceutil/trace.go:171","msg":"trace[2013732923] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1291; }","duration":"293.010028ms","start":"2026-05-10T04:05:12.213581Z","end":"2026-05-10T04:05:12.506591Z","steps":["trace[2013732923] 'agreement among raft nodes before linearized reading' (duration: 292.926855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:12.506729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:11.9962Z","time spent":"510.373855ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:05:13.62341Z","caller":"traceutil/trace.go:171","msg":"trace[877861542] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"257.901127ms","start":"2026-05-10T04:05:13.365472Z","end":"2026-05-10T04:05:13.623374Z","steps":["trace[877861542] 'process raft request' (duration: 257.679561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:13.984197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.356522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-10T04:05:13.984219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.931694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T04:05:13.9843Z","caller":"traceutil/trace.go:171","msg":"trace[956649047] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:1292; }","duration":"133.484035ms","start":"2026-05-10T04:05:13.85079Z","end":"2026-05-10T04:05:13.984274Z","steps":["trace[956649047] 'range keys from in-memory index tree' (duration: 133.136186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:13.984369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.669279ms","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-10T04:05:13.984422Z","caller":"traceutil/trace.go:171","msg":"trace[789754614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"168.760572ms","start":"2026-05-10T04:05:13.815644Z","end":"2026-05-10T04:05:13.984405Z","steps":["trace[789754614] 'range keys from in-memory index tree' (duration: 168.495213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:13.984192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.608652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:05:13.984335Z","caller":"traceutil/trace.go:171","msg":"trace[693854338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1292; }","duration":"190.121241ms","start":"2026-05-10T04:05:13.794181Z","end":"2026-05-10T04:05:13.984302Z","steps":["trace[693854338] 'range keys from in-memory index tree' (duration: 189.656707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:13.9845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.577761ms","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-10T04:05:13.984498Z","caller":"traceutil/trace.go:171","msg":"trace[2101984583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"249.994052ms","start":"2026-05-10T04:05:13.73447Z","end":"2026-05-10T04:05:13.984464Z","steps":["trace[2101984583] 'range keys from in-memory index tree' (duration: 249.500719ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:13.984571Z","caller":"traceutil/trace.go:171","msg":"trace[117951734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1292; }","duration":"249.716935ms","start":"2026-05-10T04:05:13.734839Z","end":"2026-05-10T04:05:13.984556Z","steps":["trace[117951734] 'range keys from in-memory index tree' (duration: 249.319544ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:14.296907Z","caller":"traceutil/trace.go:171","msg":"trace[366894331] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1346; }","duration":"254.611678ms","start":"2026-05-10T04:05:14.042226Z","end":"2026-05-10T04:05:14.296837Z","steps":["trace[366894331] 'read index received' (duration: 15.11386ms)","trace[366894331] 'applied index is now lower than readState.Index' (duration: 239.496728ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:05:14.297095Z","caller":"traceutil/trace.go:171","msg":"trace[779651893] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"265.613056ms","start":"2026-05-10T04:05:14.031442Z","end":"2026-05-10T04:05:14.297055Z","steps":["trace[779651893] 'process raft request' (duration: 221.578203ms)","trace[779651893] 'compare' (duration: 43.656612ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:05:14.297156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.901205ms","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-10T04:05:14.297184Z","caller":"traceutil/trace.go:171","msg":"trace[521751837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1296; }","duration":"254.986438ms","start":"2026-05-10T04:05:14.042187Z","end":"2026-05-10T04:05:14.297174Z","steps":["trace[521751837] 'agreement among raft nodes before linearized reading' (duration: 254.806673ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:14.793802Z","caller":"traceutil/trace.go:171","msg":"trace[1452380860] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"491.395181ms","start":"2026-05-10T04:05:14.302373Z","end":"2026-05-10T04:05:14.793768Z","steps":["trace[1452380860] 'read index received' (duration: 443.055382ms)","trace[1452380860] 'applied index is now lower than readState.Index' (duration: 48.338089ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:05:14.793838Z","caller":"traceutil/trace.go:171","msg":"trace[754431526] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"492.172452ms","start":"2026-05-10T04:05:14.301638Z","end":"2026-05-10T04:05:14.79381Z","steps":["trace[754431526] 'process raft request' (duration: 443.879534ms)","trace[754431526] 'compare' (duration: 48.096573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:05:14.794006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:14.301621Z","time spent":"492.279316ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:05:14.794033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.660509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-10T04:05:14.794056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.997198ms","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-10T04:05:14.794079Z","caller":"traceutil/trace.go:171","msg":"trace[1190851551] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1297; }","duration":"491.760643ms","start":"2026-05-10T04:05:14.302306Z","end":"2026-05-10T04:05:14.794067Z","steps":["trace[1190851551] 'agreement among raft nodes before linearized reading' (duration: 491.583468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:14.794111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:14.302278Z","time spent":"491.825583ms","remote":"127.0.0.1:53506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-05-10T04:05:14.794111Z","caller":"traceutil/trace.go:171","msg":"trace[1152746430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1297; }","duration":"282.093392ms","start":"2026-05-10T04:05:14.512001Z","end":"2026-05-10T04:05:14.794095Z","steps":["trace[1152746430] 'agreement among raft nodes before linearized reading' (duration: 281.962479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:14.794173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.704219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T04:05:14.794242Z","caller":"traceutil/trace.go:171","msg":"trace[1614546470] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1297; }","duration":"237.830062ms","start":"2026-05-10T04:05:14.556393Z","end":"2026-05-10T04:05:14.794223Z","steps":["trace[1614546470] 'agreement among raft nodes before linearized reading' (duration: 237.710979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:15.067101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.414313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:05:15.067296Z","caller":"traceutil/trace.go:171","msg":"trace[1833898848] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"266.333925ms","start":"2026-05-10T04:05:14.800897Z","end":"2026-05-10T04:05:15.067231Z","steps":["trace[1833898848] 'read index received' (duration: 104.201243ms)","trace[1833898848] 'applied index is now lower than readState.Index' (duration: 162.130952ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:05:15.067306Z","caller":"traceutil/trace.go:171","msg":"trace[2004921965] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"266.88199ms","start":"2026-05-10T04:05:14.800381Z","end":"2026-05-10T04:05:15.067263Z","steps":["trace[2004921965] 'process raft request' (duration: 104.764788ms)","trace[2004921965] 'compare' (duration: 161.240688ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:05:15.0675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.575501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-10T04:05:15.067607Z","caller":"traceutil/trace.go:171","msg":"trace[833200621] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1298; }","duration":"266.725385ms","start":"2026-05-10T04:05:14.800857Z","end":"2026-05-10T04:05:15.067583Z","steps":["trace[833200621] 'agreement among raft nodes before linearized reading' (duration: 266.462888ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:15.75358Z","caller":"traceutil/trace.go:171","msg":"trace[1121278315] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"125.17697ms","start":"2026-05-10T04:05:15.62837Z","end":"2026-05-10T04:05:15.753547Z","steps":["trace[1121278315] 'process raft request' (duration: 124.496232ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:17.876565Z","caller":"traceutil/trace.go:171","msg":"trace[1453385588] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"117.335959ms","start":"2026-05-10T04:05:17.7592Z","end":"2026-05-10T04:05:17.876536Z","steps":["trace[1453385588] 'process raft request' (duration: 117.153934ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:21.909891Z","caller":"traceutil/trace.go:171","msg":"trace[1383263069] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"178.306509ms","start":"2026-05-10T04:05:21.731563Z","end":"2026-05-10T04:05:21.90987Z","steps":["trace[1383263069] 'read index received' (duration: 178.127195ms)","trace[1383263069] 'applied index is now lower than readState.Index' (duration: 178.084µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:05:21.909942Z","caller":"traceutil/trace.go:171","msg":"trace[941104931] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"394.562869ms","start":"2026-05-10T04:05:21.515344Z","end":"2026-05-10T04:05:21.909907Z","steps":["trace[941104931] 'process raft request' (duration: 394.389194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:21.910004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.432043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:05:21.910051Z","caller":"traceutil/trace.go:171","msg":"trace[534704274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1340; }","duration":"178.532186ms","start":"2026-05-10T04:05:21.73151Z","end":"2026-05-10T04:05:21.910043Z","steps":["trace[534704274] 'agreement among raft nodes before linearized reading' (duration: 178.450754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:21.910101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:21.515321Z","time spent":"394.693082ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:05:22.412191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970380026257256,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T04:05:22.913024Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970380026257256,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T04:05:22.97329Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.063593303s","expected-duration":"1s"} {"level":"info","ts":"2026-05-10T04:05:23.012362Z","caller":"traceutil/trace.go:171","msg":"trace[1391073858] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"1.100842292s","start":"2026-05-10T04:05:21.911489Z","end":"2026-05-10T04:05:23.012331Z","steps":["trace[1391073858] 'read index received' (duration: 1.062108423s)","trace[1391073858] 'applied index is now lower than readState.Index' (duration: 38.725608ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:05:23.012417Z","caller":"traceutil/trace.go:171","msg":"trace[1941586017] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"1.119108927s","start":"2026-05-10T04:05:21.893274Z","end":"2026-05-10T04:05:23.012383Z","steps":["trace[1941586017] 'process raft request' (duration: 1.080356097s)","trace[1941586017] 'compare' (duration: 38.189645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:05:23.012577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101071367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T04:05:23.012572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:21.893249Z","time spent":"1.11922566s","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:05:23.012611Z","caller":"traceutil/trace.go:171","msg":"trace[1096458035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1341; }","duration":"1.10118775s","start":"2026-05-10T04:05:21.911414Z","end":"2026-05-10T04:05:23.012602Z","steps":["trace[1096458035] 'agreement among raft nodes before linearized reading' (duration: 1.101093268s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:23.012655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:21.91139Z","time spent":"1.101259332s","remote":"127.0.0.1:53454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T04:05:23.012669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.632441ms","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-10T04:05:23.012752Z","caller":"traceutil/trace.go:171","msg":"trace[1118637977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1341; }","duration":"878.768046ms","start":"2026-05-10T04:05:22.133963Z","end":"2026-05-10T04:05:23.012731Z","steps":["trace[1118637977] 'agreement among raft nodes before linearized reading' (duration: 878.57021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:23.012809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:22.133941Z","time spent":"878.860438ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-10T04:05:23.012934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.673243ms","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-10T04:05:23.012956Z","caller":"traceutil/trace.go:171","msg":"trace[2122458564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1341; }","duration":"146.733365ms","start":"2026-05-10T04:05:22.866216Z","end":"2026-05-10T04:05:23.01295Z","steps":["trace[2122458564] 'agreement among raft nodes before linearized reading' (duration: 146.676894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:23.013183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.657126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-10T04:05:23.01323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.988525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-10T04:05:23.01326Z","caller":"traceutil/trace.go:171","msg":"trace[645583116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1341; }","duration":"863.760709ms","start":"2026-05-10T04:05:22.14946Z","end":"2026-05-10T04:05:23.013221Z","steps":["trace[645583116] 'agreement among raft nodes before linearized reading' (duration: 863.594154ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:23.01329Z","caller":"traceutil/trace.go:171","msg":"trace[1792872961] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1341; }","duration":"610.100367ms","start":"2026-05-10T04:05:22.403178Z","end":"2026-05-10T04:05:23.013278Z","steps":["trace[1792872961] 'agreement among raft nodes before linearized reading' (duration: 609.912012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:23.013309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.564223ms","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-10T04:05:23.013323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:22.403149Z","time spent":"610.165598ms","remote":"127.0.0.1:53554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":149,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-10T04:05:23.013339Z","caller":"traceutil/trace.go:171","msg":"trace[942311391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1341; }","duration":"798.593774ms","start":"2026-05-10T04:05:22.214737Z","end":"2026-05-10T04:05:23.013331Z","steps":["trace[942311391] 'agreement among raft nodes before linearized reading' (duration: 798.47001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:23.01336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:22.214697Z","time spent":"798.658296ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T04:05:23.013341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:22.149428Z","time spent":"863.875562ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T04:05:47.389829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.1134ms","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-10T04:05:47.389937Z","caller":"traceutil/trace.go:171","msg":"trace[75497828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1472; }","duration":"131.262035ms","start":"2026-05-10T04:05:47.258657Z","end":"2026-05-10T04:05:47.389919Z","steps":["trace[75497828] 'range keys from in-memory index tree' (duration: 130.964087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:47.389862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.762222ms","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-10T04:05:47.390082Z","caller":"traceutil/trace.go:171","msg":"trace[1558407405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1472; }","duration":"130.997938ms","start":"2026-05-10T04:05:47.259059Z","end":"2026-05-10T04:05:47.390057Z","steps":["trace[1558407405] 'range keys from in-memory index tree' (duration: 130.598366ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:47.511489Z","caller":"traceutil/trace.go:171","msg":"trace[621796026] linearizableReadLoop","detail":"{readStateIndex:1533; appliedIndex:1532; }","duration":"245.756132ms","start":"2026-05-10T04:05:47.265712Z","end":"2026-05-10T04:05:47.511468Z","steps":["trace[621796026] 'read index received' (duration: 245.69015ms)","trace[621796026] 'applied index is now lower than readState.Index' (duration: 64.852µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:05:47.511594Z","caller":"traceutil/trace.go:171","msg":"trace[1817476416] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"250.370498ms","start":"2026-05-10T04:05:47.261215Z","end":"2026-05-10T04:05:47.511586Z","steps":["trace[1817476416] 'process raft request' (duration: 250.108291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:47.511681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.946138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:05:47.511747Z","caller":"traceutil/trace.go:171","msg":"trace[358445281] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1473; }","duration":"246.06178ms","start":"2026-05-10T04:05:47.265666Z","end":"2026-05-10T04:05:47.511728Z","steps":["trace[358445281] 'agreement among raft nodes before linearized reading' (duration: 245.951117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:47.727302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.329122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:05:47.727697Z","caller":"traceutil/trace.go:171","msg":"trace[1570940971] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"332.048869ms","start":"2026-05-10T04:05:47.395539Z","end":"2026-05-10T04:05:47.727588Z","steps":["trace[1570940971] 'process raft request' (duration: 213.339357ms)","trace[1570940971] 'compare' (duration: 118.072084ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:05:47.727853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:47.395509Z","time spent":"332.258325ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:05:47.728323Z","caller":"traceutil/trace.go:171","msg":"trace[1614401994] linearizableReadLoop","detail":"{readStateIndex:1535; appliedIndex:1533; }","duration":"216.747989ms","start":"2026-05-10T04:05:47.511555Z","end":"2026-05-10T04:05:47.728303Z","steps":["trace[1614401994] 'read index received' (duration: 97.183333ms)","trace[1614401994] 'applied index is now lower than readState.Index' (duration: 119.563296ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:05:47.728969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.134273ms","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-10T04:05:47.729005Z","caller":"traceutil/trace.go:171","msg":"trace[1577022930] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"332.948264ms","start":"2026-05-10T04:05:47.396018Z","end":"2026-05-10T04:05:47.728967Z","steps":["trace[1577022930] 'process raft request' (duration: 332.170902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:47.72904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.627216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-10T04:05:47.729043Z","caller":"traceutil/trace.go:171","msg":"trace[996455000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1475; }","duration":"361.246936ms","start":"2026-05-10T04:05:47.367777Z","end":"2026-05-10T04:05:47.729024Z","steps":["trace[996455000] 'agreement among raft nodes before linearized reading' (duration: 360.765423ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:47.729084Z","caller":"traceutil/trace.go:171","msg":"trace[610676747] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1475; }","duration":"168.672458ms","start":"2026-05-10T04:05:47.560399Z","end":"2026-05-10T04:05:47.729072Z","steps":["trace[610676747] 'agreement among raft nodes before linearized reading' (duration: 168.517763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:05:47.729093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:47.367756Z","time spent":"361.328599ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-10T04:05:47.729167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:05:47.396007Z","time spent":"333.072138ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T04:05:47.923282Z","caller":"traceutil/trace.go:171","msg":"trace[2098689080] linearizableReadLoop","detail":"{readStateIndex:1537; appliedIndex:1536; }","duration":"189.014152ms","start":"2026-05-10T04:05:47.734233Z","end":"2026-05-10T04:05:47.923248Z","steps":["trace[2098689080] 'read index received' (duration: 38.901042ms)","trace[2098689080] 'applied index is now lower than readState.Index' (duration: 150.11146ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:05:47.923519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.24073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T04:05:47.923645Z","caller":"traceutil/trace.go:171","msg":"trace[901968015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1477; }","duration":"189.421374ms","start":"2026-05-10T04:05:47.734199Z","end":"2026-05-10T04:05:47.92362Z","steps":["trace[901968015] 'agreement among raft nodes before linearized reading' (duration: 189.172307ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:05:47.923738Z","caller":"traceutil/trace.go:171","msg":"trace[1993637494] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"189.842075ms","start":"2026-05-10T04:05:47.733677Z","end":"2026-05-10T04:05:47.923519Z","steps":["trace[1993637494] 'process raft request' (duration: 98.578103ms)","trace[1993637494] 'compare' (duration: 90.79911ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:06:03.737729Z","caller":"traceutil/trace.go:171","msg":"trace[1215254111] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"107.99853ms","start":"2026-05-10T04:06:03.629702Z","end":"2026-05-10T04:06:03.737701Z","steps":["trace[1215254111] 'process raft request' (duration: 107.795364ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:03.984966Z","caller":"traceutil/trace.go:171","msg":"trace[1362166453] linearizableReadLoop","detail":"{readStateIndex:1609; appliedIndex:1607; }","duration":"285.541448ms","start":"2026-05-10T04:06:03.699379Z","end":"2026-05-10T04:06:03.98492Z","steps":["trace[1362166453] 'read index received' (duration: 38.126101ms)","trace[1362166453] 'applied index is now lower than readState.Index' (duration: 247.413257ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:06:03.985498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.210327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-10T04:06:03.985551Z","caller":"traceutil/trace.go:171","msg":"trace[1861246424] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"355.820498ms","start":"2026-05-10T04:06:03.629696Z","end":"2026-05-10T04:06:03.985516Z","steps":["trace[1861246424] 'process raft request' (duration: 261.78355ms)","trace[1861246424] 'compare' (duration: 92.856256ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:06:03.985602Z","caller":"traceutil/trace.go:171","msg":"trace[1416116198] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:1546; }","duration":"130.35781ms","start":"2026-05-10T04:06:03.855218Z","end":"2026-05-10T04:06:03.985576Z","steps":["trace[1416116198] 'agreement among raft nodes before linearized reading' (duration: 130.063883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:03.985729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:06:03.629667Z","time spent":"355.969442ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T04:06:03.985775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.370801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-10T04:06:03.985856Z","caller":"traceutil/trace.go:171","msg":"trace[1088737036] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1546; }","duration":"286.496926ms","start":"2026-05-10T04:06:03.699336Z","end":"2026-05-10T04:06:03.985832Z","steps":["trace[1088737036] 'agreement among raft nodes before linearized reading' (duration: 286.345761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:03.986032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.790481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T04:06:03.986089Z","caller":"traceutil/trace.go:171","msg":"trace[874387547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1546; }","duration":"108.869244ms","start":"2026-05-10T04:06:03.877186Z","end":"2026-05-10T04:06:03.986056Z","steps":["trace[874387547] 'agreement among raft nodes before linearized reading' (duration: 108.720149ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:04.261374Z","caller":"traceutil/trace.go:171","msg":"trace[947592364] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"268.870644ms","start":"2026-05-10T04:06:03.99247Z","end":"2026-05-10T04:06:04.26134Z","steps":["trace[947592364] 'process raft request' (duration: 268.455893ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:04.263881Z","caller":"traceutil/trace.go:171","msg":"trace[939721910] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"271.255089ms","start":"2026-05-10T04:06:03.992595Z","end":"2026-05-10T04:06:04.26385Z","steps":["trace[939721910] 'process raft request' (duration: 268.614868ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:04.264744Z","caller":"traceutil/trace.go:171","msg":"trace[325919668] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"271.408212ms","start":"2026-05-10T04:06:03.993266Z","end":"2026-05-10T04:06:04.264674Z","steps":["trace[325919668] 'process raft request' (duration: 270.379554ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:04.265098Z","caller":"traceutil/trace.go:171","msg":"trace[590047752] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"270.826197ms","start":"2026-05-10T04:06:03.994252Z","end":"2026-05-10T04:06:04.265078Z","steps":["trace[590047752] 'process raft request' (duration: 270.19925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:04.265475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.581523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-10T04:06:04.265527Z","caller":"traceutil/trace.go:171","msg":"trace[566811536] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1550; }","duration":"243.688365ms","start":"2026-05-10T04:06:04.021823Z","end":"2026-05-10T04:06:04.265511Z","steps":["trace[566811536] 'agreement among raft nodes before linearized reading' (duration: 243.450448ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:04.266709Z","caller":"traceutil/trace.go:171","msg":"trace[647971876] linearizableReadLoop","detail":"{readStateIndex:1614; appliedIndex:1609; }","duration":"244.829818ms","start":"2026-05-10T04:06:04.021864Z","end":"2026-05-10T04:06:04.266694Z","steps":["trace[647971876] 'read index received' (duration: 102.457649ms)","trace[647971876] 'applied index is now lower than readState.Index' (duration: 140.707073ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:06:04.347484Z","caller":"traceutil/trace.go:171","msg":"trace[229417869] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"166.976439ms","start":"2026-05-10T04:06:04.180471Z","end":"2026-05-10T04:06:04.347448Z","steps":["trace[229417869] 'process raft request' (duration: 137.779622ms)","trace[229417869] 'compare' (duration: 29.032594ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:06:10.629839Z","caller":"traceutil/trace.go:171","msg":"trace[1233057938] transaction","detail":"{read_only:false; response_revision:1580; number_of_response:1; }","duration":"203.2082ms","start":"2026-05-10T04:06:10.426518Z","end":"2026-05-10T04:06:10.629726Z","steps":["trace[1233057938] 'process raft request' (duration: 202.996824ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:14.798102Z","caller":"traceutil/trace.go:171","msg":"trace[940758388] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"146.596045ms","start":"2026-05-10T04:06:14.651468Z","end":"2026-05-10T04:06:14.798064Z","steps":["trace[940758388] 'process raft request' (duration: 146.333097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:06:40.742092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.040462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T04:06:40.742469Z","caller":"traceutil/trace.go:171","msg":"trace[2086721497] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"166.865008ms","start":"2026-05-10T04:06:40.575363Z","end":"2026-05-10T04:06:40.742228Z","steps":["trace[2086721497] 'process raft request' (duration: 58.559669ms)","trace[2086721497] 'compare' (duration: 107.894757ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:06:40.776019Z","caller":"traceutil/trace.go:171","msg":"trace[214919157] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"200.548388ms","start":"2026-05-10T04:06:40.575434Z","end":"2026-05-10T04:06:40.775983Z","steps":["trace[214919157] 'process raft request' (duration: 200.300331ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:44.895764Z","caller":"traceutil/trace.go:171","msg":"trace[933184785] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"129.433211ms","start":"2026-05-10T04:06:44.766298Z","end":"2026-05-10T04:06:44.895731Z","steps":["trace[933184785] 'process raft request' (duration: 128.951269ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:06:45.037948Z","caller":"traceutil/trace.go:171","msg":"trace[2119410283] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"136.870673ms","start":"2026-05-10T04:06:44.901034Z","end":"2026-05-10T04:06:45.037905Z","steps":["trace[2119410283] 'process raft request' (duration: 99.914375ms)","trace[2119410283] 'compare' (duration: 36.797823ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T04:06:48.778078Z","caller":"traceutil/trace.go:171","msg":"trace[1637498008] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"165.487926ms","start":"2026-05-10T04:06:48.612548Z","end":"2026-05-10T04:06:48.778036Z","steps":["trace[1637498008] 'process raft request' (duration: 165.219018ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:07:37.190738Z","caller":"traceutil/trace.go:171","msg":"trace[1516137092] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"148.040734ms","start":"2026-05-10T04:07:37.042668Z","end":"2026-05-10T04:07:37.190709Z","steps":["trace[1516137092] 'process raft request' (duration: 147.852089ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:08:47.078351Z","caller":"traceutil/trace.go:171","msg":"trace[1906864898] transaction","detail":"{read_only:false; response_revision:2315; number_of_response:1; }","duration":"269.093842ms","start":"2026-05-10T04:08:46.809142Z","end":"2026-05-10T04:08:47.078236Z","steps":["trace[1906864898] 'process raft request' (duration: 268.852676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:08:56.15037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.220948ms","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-10T04:08:56.150638Z","caller":"traceutil/trace.go:171","msg":"trace[2056281571] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2364; }","duration":"123.558269ms","start":"2026-05-10T04:08:56.027058Z","end":"2026-05-10T04:08:56.150616Z","steps":["trace[2056281571] 'count revisions from in-memory index tree' (duration: 123.130147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T04:08:56.150673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.77293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-10T04:08:56.150805Z","caller":"traceutil/trace.go:171","msg":"trace[1976916154] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2364; }","duration":"223.978286ms","start":"2026-05-10T04:08:55.926802Z","end":"2026-05-10T04:08:56.15078Z","steps":["trace[1976916154] 'range keys from in-memory index tree' (duration: 223.514922ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T04:08:56.644826Z","caller":"traceutil/trace.go:171","msg":"trace[2055057269] transaction","detail":"{read_only:false; response_revision:2366; number_of_response:1; }","duration":"474.395721ms","start":"2026-05-10T04:08:56.17039Z","end":"2026-05-10T04:08:56.644786Z","steps":["trace[2055057269] 'process raft request' (duration: 454.279963ms)","trace[2055057269] 'compare' (duration: 19.114631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T04:08:56.645313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T04:08:56.170358Z","time spent":"474.633167ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}