{"level":"warn","ts":"2026-05-21T18:49:35.946075Z","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-21T18:49:35.946195Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244: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.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-21T18:49:35.946264Z","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-21T18:49:35.946278Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-05-21T18:49:35.946305Z","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-21T18:49:35.946993Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-05-21T18:49:35.947067Z","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.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-21T18:49:35.953583Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.333794ms"} {"level":"info","ts":"2026-05-21T18:49:35.959815Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-05-21T18:49:35.959911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T18:49:35.95994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-05-21T18:49:35.959985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T18:49:35.959996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-05-21T18:49:35.960038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-05-21T18:49:35.966254Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T18:49:35.967607Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T18:49:35.968751Z","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-21T18:49:35.971143Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T18:49:35.971326Z","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-21T18:49:35.9718Z","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-21T18:49:35.97183Z","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-21T18:49:35.972204Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T18:49:35.973975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-05-21T18:49:35.974164Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-05-21T18:49:35.977754Z","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-21T18:49:35.977786Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-05-21T18:49:35.977845Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-05-21T18:49:35.978193Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T18:49:35.978257Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T18:49:36.861043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T18:49:36.86109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T18:49:36.861127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-05-21T18:49:36.86114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-05-21T18:49:36.861152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-05-21T18:49:36.861163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-05-21T18:49:36.861177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-05-21T18:49:36.861857Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T18:49:36.862461Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T18:49:36.862473Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T18:49:36.862855Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T18:49:36.862923Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T18:49:36.86295Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T18:49:36.863018Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T18:49:36.863054Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T18:49:36.866545Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T18:49:36.866741Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T18:49:36.868874Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"warn","ts":"2026-05-21T18:49:46.255101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.861612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-21T18:49:46.255196Z","caller":"traceutil/trace.go:171","msg":"trace[24370102] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:334; }","duration":"272.029926ms","start":"2026-05-21T18:49:45.983147Z","end":"2026-05-21T18:49:46.255176Z","steps":["trace[24370102] 'range keys from in-memory index tree' (duration: 271.732029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:49:46.255243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.118812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-21T18:49:46.255335Z","caller":"traceutil/trace.go:171","msg":"trace[677648149] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:334; }","duration":"319.262806ms","start":"2026-05-21T18:49:45.936053Z","end":"2026-05-21T18:49:46.255316Z","steps":["trace[677648149] 'range keys from in-memory index tree' (duration: 318.834605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:49:46.255388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:49:45.936034Z","time spent":"319.343538ms","remote":"127.0.0.1:34578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-05-21T18:49:51.817953Z","caller":"traceutil/trace.go:171","msg":"trace[1089075729] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"109.287259ms","start":"2026-05-21T18:49:51.708648Z","end":"2026-05-21T18:49:51.817936Z","steps":["trace[1089075729] 'process raft request' (duration: 109.190066ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:49:52.895729Z","caller":"traceutil/trace.go:171","msg":"trace[1316829819] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"527.324056ms","start":"2026-05-21T18:49:52.368382Z","end":"2026-05-21T18:49:52.895706Z","steps":["trace[1316829819] 'process raft request' (duration: 527.179632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:49:52.896541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:49:52.368362Z","time spent":"527.45751ms","remote":"127.0.0.1:34616","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-21T18:49:53.427368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.940661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T18:49:53.427469Z","caller":"traceutil/trace.go:171","msg":"trace[1598055082] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:386; }","duration":"792.802355ms","start":"2026-05-21T18:49:52.634651Z","end":"2026-05-21T18:49:53.427453Z","steps":["trace[1598055082] 'read index received' (duration: 260.880396ms)","trace[1598055082] 'applied index is now lower than readState.Index' (duration: 531.920649ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:49:53.427675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.644658ms","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-21T18:49:53.427737Z","caller":"traceutil/trace.go:171","msg":"trace[363723526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:372; }","duration":"729.74547ms","start":"2026-05-21T18:49:52.697977Z","end":"2026-05-21T18:49:53.427723Z","steps":["trace[363723526] 'agreement among raft nodes before linearized reading' (duration: 729.636937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:49:53.427764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.862774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T18:49:53.427774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:49:52.697958Z","time spent":"729.807411ms","remote":"127.0.0.1:34616","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-21T18:49:53.427766Z","caller":"traceutil/trace.go:171","msg":"trace[65682718] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"1.058157309s","start":"2026-05-21T18:49:52.369578Z","end":"2026-05-21T18:49:53.427736Z","steps":["trace[65682718] 'process raft request' (duration: 631.616224ms)","trace[65682718] 'compare' (duration: 424.918396ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:49:53.427799Z","caller":"traceutil/trace.go:171","msg":"trace[168360059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:372; }","duration":"403.936736ms","start":"2026-05-21T18:49:53.023852Z","end":"2026-05-21T18:49:53.427789Z","steps":["trace[168360059] 'agreement among raft nodes before linearized reading' (duration: 403.885104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:49:53.427838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:49:53.023831Z","time spent":"404.000317ms","remote":"127.0.0.1:34406","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-21T18:49:53.427912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:49:52.36956Z","time spent":"1.058272502s","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:49:53.428035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.626185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-05-21T18:49:53.428106Z","caller":"traceutil/trace.go:171","msg":"trace[2067341755] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:372; }","duration":"497.741148ms","start":"2026-05-21T18:49:52.930345Z","end":"2026-05-21T18:49:53.428086Z","steps":["trace[2067341755] 'agreement among raft nodes before linearized reading' (duration: 497.530313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:49:53.428154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:49:52.930325Z","time spent":"497.817469ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6987,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-05-21T18:49:53.429434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.785103ms","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-21T18:49:53.429527Z","caller":"traceutil/trace.go:171","msg":"trace[1581796032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:372; }","duration":"794.923426ms","start":"2026-05-21T18:49:52.634587Z","end":"2026-05-21T18:49:53.429511Z","steps":["trace[1581796032] 'agreement among raft nodes before linearized reading' (duration: 793.009459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:49:53.42959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:49:52.634568Z","time spent":"795.010839ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T18:49:54.032605Z","caller":"traceutil/trace.go:171","msg":"trace[239300060] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"599.760419ms","start":"2026-05-21T18:49:53.432824Z","end":"2026-05-21T18:49:54.032584Z","steps":["trace[239300060] 'process raft request' (duration: 599.546514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:49:54.032722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:49:53.432806Z","time spent":"599.855271ms","remote":"127.0.0.1:34616","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-21T18:49:54.032894Z","caller":"traceutil/trace.go:171","msg":"trace[1805385424] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"594.689326ms","start":"2026-05-21T18:49:53.438172Z","end":"2026-05-21T18:49:54.032861Z","steps":["trace[1805385424] 'process raft request' (duration: 594.374578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:49:54.033055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:49:53.438162Z","time spent":"594.802738ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T18:50:13.784512Z","caller":"traceutil/trace.go:171","msg":"trace[1040171161] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"107.318842ms","start":"2026-05-21T18:50:13.677163Z","end":"2026-05-21T18:50:13.784482Z","steps":["trace[1040171161] 'process raft request' (duration: 78.749047ms)","trace[1040171161] 'compare' (duration: 28.38461ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:50:14.663974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199817288932440,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T18:50:14.834264Z","caller":"traceutil/trace.go:171","msg":"trace[1894689650] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"677.265044ms","start":"2026-05-21T18:50:14.156974Z","end":"2026-05-21T18:50:14.834239Z","steps":["trace[1894689650] 'process raft request' (duration: 677.071689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:14.83441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.156954Z","time spent":"677.390306ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:15.114155Z","caller":"traceutil/trace.go:171","msg":"trace[302290331] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"957.07004ms","start":"2026-05-21T18:50:14.157062Z","end":"2026-05-21T18:50:15.114132Z","steps":["trace[302290331] 'process raft request' (duration: 956.837474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.114264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.157038Z","time spent":"957.174372ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:15.114256Z","caller":"traceutil/trace.go:171","msg":"trace[616200039] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"951.014462ms","start":"2026-05-21T18:50:14.163207Z","end":"2026-05-21T18:50:15.114221Z","steps":["trace[616200039] 'process raft request' (duration: 950.858368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.114405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.163197Z","time spent":"951.143056ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:15.114407Z","caller":"traceutil/trace.go:171","msg":"trace[618252904] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"935.090275ms","start":"2026-05-21T18:50:14.179286Z","end":"2026-05-21T18:50:15.114377Z","steps":["trace[618252904] 'process raft request' (duration: 934.8801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.114506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.17928Z","time spent":"935.180907ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:15.114676Z","caller":"traceutil/trace.go:171","msg":"trace[253413679] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"935.124086ms","start":"2026-05-21T18:50:14.179222Z","end":"2026-05-21T18:50:15.114346Z","steps":["trace[253413679] 'process raft request' (duration: 934.89516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.114762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.179206Z","time spent":"935.503596ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:15.115038Z","caller":"traceutil/trace.go:171","msg":"trace[1867499195] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"932.321668ms","start":"2026-05-21T18:50:14.182701Z","end":"2026-05-21T18:50:15.115023Z","steps":["trace[1867499195] 'process raft request' (duration: 931.908578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.115101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.182681Z","time spent":"932.392819ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:15.115113Z","caller":"traceutil/trace.go:171","msg":"trace[814062075] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"928.716481ms","start":"2026-05-21T18:50:14.186381Z","end":"2026-05-21T18:50:15.115097Z","steps":["trace[814062075] 'process raft request' (duration: 928.453195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.115239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.186365Z","time spent":"928.777832ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:15.115311Z","caller":"traceutil/trace.go:171","msg":"trace[688225720] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"924.15646ms","start":"2026-05-21T18:50:14.191142Z","end":"2026-05-21T18:50:15.115298Z","steps":["trace[688225720] 'process raft request' (duration: 923.834442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.115363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.191122Z","time spent":"924.220601ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:15.118427Z","caller":"traceutil/trace.go:171","msg":"trace[672012033] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"916.586745ms","start":"2026-05-21T18:50:14.201821Z","end":"2026-05-21T18:50:15.118407Z","steps":["trace[672012033] 'process raft request' (duration: 915.614152ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:15.118424Z","caller":"traceutil/trace.go:171","msg":"trace[1550074392] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:506; }","duration":"955.337918ms","start":"2026-05-21T18:50:14.163061Z","end":"2026-05-21T18:50:15.118399Z","steps":["trace[1550074392] 'read index received' (duration: 671.060243ms)","trace[1550074392] 'applied index is now lower than readState.Index' (duration: 284.276575ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:50:15.118506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.201799Z","time spent":"916.675237ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:15.118527Z","caller":"traceutil/trace.go:171","msg":"trace[1113710515] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"900.599237ms","start":"2026-05-21T18:50:14.217919Z","end":"2026-05-21T18:50:15.118518Z","steps":["trace[1113710515] 'process raft request' (duration: 899.591743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.118619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.217903Z","time spent":"900.650198ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:15.118695Z","caller":"traceutil/trace.go:171","msg":"trace[1304251896] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"917.103497ms","start":"2026-05-21T18:50:14.201577Z","end":"2026-05-21T18:50:15.118681Z","steps":["trace[1304251896] 'process raft request' (duration: 913.455728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.118796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.201563Z","time spent":"917.2026ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:15.118854Z","caller":"traceutil/trace.go:171","msg":"trace[1696237958] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"786.554153ms","start":"2026-05-21T18:50:14.332288Z","end":"2026-05-21T18:50:15.118842Z","steps":["trace[1696237958] 'process raft request' (duration: 786.03649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.11891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.332254Z","time spent":"786.631674ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T18:50:15.11901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.940122ms","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-21T18:50:15.119056Z","caller":"traceutil/trace.go:171","msg":"trace[876999068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:497; }","duration":"956.019895ms","start":"2026-05-21T18:50:14.163025Z","end":"2026-05-21T18:50:15.119045Z","steps":["trace[876999068] 'agreement among raft nodes before linearized reading' (duration: 955.904892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.1191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.163008Z","time spent":"956.082016ms","remote":"127.0.0.1:34616","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-21T18:50:15.239988Z","caller":"traceutil/trace.go:171","msg":"trace[2056545730] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"398.714169ms","start":"2026-05-21T18:50:14.84124Z","end":"2026-05-21T18:50:15.239954Z","steps":["trace[2056545730] 'process raft request' (duration: 386.556553ms)","trace[2056545730] 'compare' (duration: 11.784047ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:50:15.240097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.84122Z","time spent":"398.828291ms","remote":"127.0.0.1:34890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:15.240329Z","caller":"traceutil/trace.go:171","msg":"trace[1113067758] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"398.36894ms","start":"2026-05-21T18:50:14.841952Z","end":"2026-05-21T18:50:15.240321Z","steps":["trace[1113067758] 'process raft request' (duration: 397.782146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.240384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.841944Z","time spent":"398.408161ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T18:50:15.240616Z","caller":"traceutil/trace.go:171","msg":"trace[428867136] transaction","detail":"{read_only:false; number_of_response:1; response_revision:499; }","duration":"397.846779ms","start":"2026-05-21T18:50:14.842748Z","end":"2026-05-21T18:50:15.240594Z","steps":["trace[428867136] 'process raft request' (duration: 397.051699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.240924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:14.842739Z","time spent":"398.137896ms","remote":"127.0.0.1:34458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6936,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T18:50:15.247739Z","caller":"traceutil/trace.go:171","msg":"trace[525899494] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:518; }","duration":"129.243294ms","start":"2026-05-21T18:50:15.118485Z","end":"2026-05-21T18:50:15.247728Z","steps":["trace[525899494] 'read index received' (duration: 109.392931ms)","trace[525899494] 'applied index is now lower than readState.Index' (duration: 19.849733ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:50:15.248124Z","caller":"traceutil/trace.go:171","msg":"trace[287249069] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"121.588127ms","start":"2026-05-21T18:50:15.126519Z","end":"2026-05-21T18:50:15.248107Z","steps":["trace[287249069] 'process raft request' (duration: 121.125766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.2482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.347899ms","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-21T18:50:15.248402Z","caller":"traceutil/trace.go:171","msg":"trace[494501525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:500; }","duration":"250.620686ms","start":"2026-05-21T18:50:14.997772Z","end":"2026-05-21T18:50:15.248393Z","steps":["trace[494501525] 'agreement among raft nodes before linearized reading' (duration: 250.025352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:15.248553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.435199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:50:15.248615Z","caller":"traceutil/trace.go:171","msg":"trace[245269909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"224.536852ms","start":"2026-05-21T18:50:15.024066Z","end":"2026-05-21T18:50:15.248603Z","steps":["trace[245269909] 'agreement among raft nodes before linearized reading' (duration: 223.824905ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:15.249356Z","caller":"traceutil/trace.go:171","msg":"trace[1683582189] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"122.644863ms","start":"2026-05-21T18:50:15.126683Z","end":"2026-05-21T18:50:15.249328Z","steps":["trace[1683582189] 'process raft request' (duration: 121.226388ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:15.24937Z","caller":"traceutil/trace.go:171","msg":"trace[1658046771] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"122.448249ms","start":"2026-05-21T18:50:15.126902Z","end":"2026-05-21T18:50:15.24935Z","steps":["trace[1658046771] 'process raft request' (duration: 122.048008ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:15.24989Z","caller":"traceutil/trace.go:171","msg":"trace[1094968463] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"122.781997ms","start":"2026-05-21T18:50:15.127096Z","end":"2026-05-21T18:50:15.249878Z","steps":["trace[1094968463] 'process raft request' (duration: 121.989988ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:15.251847Z","caller":"traceutil/trace.go:171","msg":"trace[1159074181] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"120.158203ms","start":"2026-05-21T18:50:15.131649Z","end":"2026-05-21T18:50:15.251807Z","steps":["trace[1159074181] 'process raft request' (duration: 117.470887ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:15.253305Z","caller":"traceutil/trace.go:171","msg":"trace[819079594] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"119.20491ms","start":"2026-05-21T18:50:15.134075Z","end":"2026-05-21T18:50:15.25328Z","steps":["trace[819079594] 'process raft request' (duration: 116.204837ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:15.253354Z","caller":"traceutil/trace.go:171","msg":"trace[1325590697] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"116.033252ms","start":"2026-05-21T18:50:15.137305Z","end":"2026-05-21T18:50:15.253338Z","steps":["trace[1325590697] 'process raft request' (duration: 113.05594ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:15.253707Z","caller":"traceutil/trace.go:171","msg":"trace[1561097265] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"118.572794ms","start":"2026-05-21T18:50:15.135112Z","end":"2026-05-21T18:50:15.253685Z","steps":["trace[1561097265] 'process raft request' (duration: 115.223532ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:21.311086Z","caller":"traceutil/trace.go:171","msg":"trace[145825127] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"287.034661ms","start":"2026-05-21T18:50:21.024029Z","end":"2026-05-21T18:50:21.311063Z","steps":["trace[145825127] 'read index received' (duration: 284.451119ms)","trace[145825127] 'applied index is now lower than readState.Index' (duration: 2.582912ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:50:21.311185Z","caller":"traceutil/trace.go:171","msg":"trace[928614734] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"863.949855ms","start":"2026-05-21T18:50:20.447229Z","end":"2026-05-21T18:50:21.311179Z","steps":["trace[928614734] 'process raft request' (duration: 862.345816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:21.311276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:20.447192Z","time spent":"864.012777ms","remote":"127.0.0.1:34500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:50:21.31133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.275328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:50:21.311423Z","caller":"traceutil/trace.go:171","msg":"trace[1402422981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"287.414091ms","start":"2026-05-21T18:50:21.023983Z","end":"2026-05-21T18:50:21.311397Z","steps":["trace[1402422981] 'agreement among raft nodes before linearized reading' (duration: 287.268798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:21.466017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.822839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T18:50:21.466146Z","caller":"traceutil/trace.go:171","msg":"trace[440557982] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"150.202464ms","start":"2026-05-21T18:50:21.315929Z","end":"2026-05-21T18:50:21.466132Z","steps":["trace[440557982] 'process raft request' (duration: 36.1833ms)","trace[440557982] 'compare' (duration: 113.715787ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:50:21.466328Z","caller":"traceutil/trace.go:171","msg":"trace[60803215] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"101.939461ms","start":"2026-05-21T18:50:21.364381Z","end":"2026-05-21T18:50:21.466321Z","steps":["trace[60803215] 'process raft request' (duration: 101.90114ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:21.466391Z","caller":"traceutil/trace.go:171","msg":"trace[1545629546] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"150.258626ms","start":"2026-05-21T18:50:21.31609Z","end":"2026-05-21T18:50:21.466349Z","steps":["trace[1545629546] 'process raft request' (duration: 150.01273ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:22.660771Z","caller":"traceutil/trace.go:171","msg":"trace[1217647478] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"142.177708ms","start":"2026-05-21T18:50:22.518565Z","end":"2026-05-21T18:50:22.660743Z","steps":["trace[1217647478] 'process raft request' (duration: 142.109846ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:22.660824Z","caller":"traceutil/trace.go:171","msg":"trace[351967402] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"200.24194ms","start":"2026-05-21T18:50:22.460525Z","end":"2026-05-21T18:50:22.660767Z","steps":["trace[351967402] 'process raft request' (duration: 193.63165ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:22.942422Z","caller":"traceutil/trace.go:171","msg":"trace[628902541] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"161.672483ms","start":"2026-05-21T18:50:22.780718Z","end":"2026-05-21T18:50:22.94239Z","steps":["trace[628902541] 'read index received' (duration: 121.635769ms)","trace[628902541] 'applied index is now lower than readState.Index' (duration: 40.035344ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:50:22.942539Z","caller":"traceutil/trace.go:171","msg":"trace[1577756666] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"162.831241ms","start":"2026-05-21T18:50:22.77961Z","end":"2026-05-21T18:50:22.942441Z","steps":["trace[1577756666] 'process raft request' (duration: 122.727895ms)","trace[1577756666] 'compare' (duration: 39.862031ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:50:22.942664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.905879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-21T18:50:22.942785Z","caller":"traceutil/trace.go:171","msg":"trace[1434355620] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:599; }","duration":"162.079663ms","start":"2026-05-21T18:50:22.780681Z","end":"2026-05-21T18:50:22.942761Z","steps":["trace[1434355620] 'agreement among raft nodes before linearized reading' (duration: 161.793726ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:23.508338Z","caller":"traceutil/trace.go:171","msg":"trace[1082646946] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"505.980458ms","start":"2026-05-21T18:50:23.002328Z","end":"2026-05-21T18:50:23.508309Z","steps":["trace[1082646946] 'process raft request' (duration: 428.193395ms)","trace[1082646946] 'compare' (duration: 77.363902ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:50:23.508636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:23.002306Z","time spent":"506.101341ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T18:50:23.840961Z","caller":"traceutil/trace.go:171","msg":"trace[1265847203] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:628; }","duration":"817.252089ms","start":"2026-05-21T18:50:23.023689Z","end":"2026-05-21T18:50:23.840941Z","steps":["trace[1265847203] 'read index received' (duration: 406.905977ms)","trace[1265847203] 'applied index is now lower than readState.Index' (duration: 410.345262ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:50:23.841047Z","caller":"traceutil/trace.go:171","msg":"trace[797426546] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"835.321949ms","start":"2026-05-21T18:50:23.005689Z","end":"2026-05-21T18:50:23.841011Z","steps":["trace[797426546] 'process raft request' (duration: 835.194956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:23.84111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.409483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:50:23.841148Z","caller":"traceutil/trace.go:171","msg":"trace[756805552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"817.497955ms","start":"2026-05-21T18:50:23.023638Z","end":"2026-05-21T18:50:23.841136Z","steps":["trace[756805552] 'agreement among raft nodes before linearized reading' (duration: 817.428264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:23.841179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:23.023621Z","time spent":"817.551276ms","remote":"127.0.0.1:34406","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-21T18:50:23.841231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.822335ms","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-21T18:50:23.841277Z","caller":"traceutil/trace.go:171","msg":"trace[638747760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"369.873147ms","start":"2026-05-21T18:50:23.471393Z","end":"2026-05-21T18:50:23.841266Z","steps":["trace[638747760] 'agreement among raft nodes before linearized reading' (duration: 369.728123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:23.841307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:23.471338Z","time spent":"369.962529ms","remote":"127.0.0.1:34616","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-21T18:50:23.84149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:23.00568Z","time spent":"835.444692ms","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T18:50:23.84105Z","caller":"traceutil/trace.go:171","msg":"trace[1018860628] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"835.858402ms","start":"2026-05-21T18:50:23.005171Z","end":"2026-05-21T18:50:23.841029Z","steps":["trace[1018860628] 'process raft request' (duration: 835.523373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:23.841558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.268682ms","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-21T18:50:23.841608Z","caller":"traceutil/trace.go:171","msg":"trace[780949482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"369.322593ms","start":"2026-05-21T18:50:23.472273Z","end":"2026-05-21T18:50:23.841596Z","steps":["trace[780949482] 'agreement among raft nodes before linearized reading' (duration: 369.18808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:23.84164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:23.472245Z","time spent":"369.387585ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T18:50:23.841641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:23.005162Z","time spent":"836.427995ms","remote":"127.0.0.1:34484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:24.017965Z","caller":"traceutil/trace.go:171","msg":"trace[1298206484] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"170.553479ms","start":"2026-05-21T18:50:23.847381Z","end":"2026-05-21T18:50:24.017934Z","steps":["trace[1298206484] 'process raft request' (duration: 170.416995ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:24.01798Z","caller":"traceutil/trace.go:171","msg":"trace[569109386] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"171.298056ms","start":"2026-05-21T18:50:23.846644Z","end":"2026-05-21T18:50:24.017942Z","steps":["trace[569109386] 'process raft request' (duration: 171.007579ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:24.018323Z","caller":"traceutil/trace.go:171","msg":"trace[163584516] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"170.873006ms","start":"2026-05-21T18:50:23.84742Z","end":"2026-05-21T18:50:24.018293Z","steps":["trace[163584516] 'process raft request' (duration: 170.465017ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:24.018359Z","caller":"traceutil/trace.go:171","msg":"trace[1134710864] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"163.123297ms","start":"2026-05-21T18:50:23.855216Z","end":"2026-05-21T18:50:24.01834Z","steps":["trace[1134710864] 'process raft request' (duration: 162.786789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:24.900702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.396446ms","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-21T18:50:24.900844Z","caller":"traceutil/trace.go:171","msg":"trace[954097294] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:610; }","duration":"376.55471ms","start":"2026-05-21T18:50:24.524263Z","end":"2026-05-21T18:50:24.900817Z","steps":["trace[954097294] 'range keys from in-memory index tree' (duration: 376.377926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:26.156118Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.131850622s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T18:50:26.773908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.520681ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-21T18:50:26.774007Z","caller":"traceutil/trace.go:171","msg":"trace[572844145] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"905.254751ms","start":"2026-05-21T18:50:25.868736Z","end":"2026-05-21T18:50:26.77399Z","steps":["trace[572844145] 'read index received' (duration: 287.534145ms)","trace[572844145] 'applied index is now lower than readState.Index' (duration: 617.719006ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:50:26.774314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.126536ms","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-21T18:50:26.774359Z","caller":"traceutil/trace.go:171","msg":"trace[1741759446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"750.201308ms","start":"2026-05-21T18:50:26.024142Z","end":"2026-05-21T18:50:26.774343Z","steps":["trace[1741759446] 'agreement among raft nodes before linearized reading' (duration: 750.060995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:26.774406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:26.024127Z","time spent":"750.27095ms","remote":"127.0.0.1:34616","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-21T18:50:26.774432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.940262ms","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-21T18:50:26.774575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.835925ms","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-21T18:50:26.774582Z","caller":"traceutil/trace.go:171","msg":"trace[2039657596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"750.137527ms","start":"2026-05-21T18:50:26.02442Z","end":"2026-05-21T18:50:26.774558Z","steps":["trace[2039657596] 'agreement among raft nodes before linearized reading' (duration: 749.817619ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:26.774622Z","caller":"traceutil/trace.go:171","msg":"trace[1145333226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"905.915807ms","start":"2026-05-21T18:50:25.868693Z","end":"2026-05-21T18:50:26.774609Z","steps":["trace[1145333226] 'agreement among raft nodes before linearized reading' (duration: 905.410135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:26.774649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:26.024402Z","time spent":"750.230888ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T18:50:26.77467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:25.868678Z","time spent":"905.984128ms","remote":"127.0.0.1:34616","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-21T18:50:26.774688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.255309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:50:26.774763Z","caller":"traceutil/trace.go:171","msg":"trace[1812856725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"750.342181ms","start":"2026-05-21T18:50:26.024411Z","end":"2026-05-21T18:50:26.774753Z","steps":["trace[1812856725] 'agreement among raft nodes before linearized reading' (duration: 750.242729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:26.7748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:26.024405Z","time spent":"750.388032ms","remote":"127.0.0.1:34394","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-21T18:50:26.774838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.309441ms","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-21T18:50:26.775755Z","caller":"traceutil/trace.go:171","msg":"trace[1778680672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"751.258844ms","start":"2026-05-21T18:50:26.024481Z","end":"2026-05-21T18:50:26.77574Z","steps":["trace[1778680672] 'agreement among raft nodes before linearized reading' (duration: 749.747098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:26.775795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:26.024457Z","time spent":"751.323735ms","remote":"127.0.0.1:34616","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-21T18:50:26.893285Z","caller":"traceutil/trace.go:171","msg":"trace[1406670259] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"113.995483ms","start":"2026-05-21T18:50:26.779256Z","end":"2026-05-21T18:50:26.893252Z","steps":["trace[1406670259] 'process raft request' (duration: 113.757908ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:26.939343Z","caller":"traceutil/trace.go:171","msg":"trace[1094742287] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"159.172642ms","start":"2026-05-21T18:50:26.78012Z","end":"2026-05-21T18:50:26.939292Z","steps":["trace[1094742287] 'process raft request' (duration: 158.865014ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:26.939646Z","caller":"traceutil/trace.go:171","msg":"trace[579520117] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"159.08019ms","start":"2026-05-21T18:50:26.780534Z","end":"2026-05-21T18:50:26.939614Z","steps":["trace[579520117] 'process raft request' (duration: 158.648269ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:26.939705Z","caller":"traceutil/trace.go:171","msg":"trace[1497080996] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"158.762262ms","start":"2026-05-21T18:50:26.780915Z","end":"2026-05-21T18:50:26.939677Z","steps":["trace[1497080996] 'process raft request' (duration: 158.596238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:28.379314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.930854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T18:50:28.379448Z","caller":"traceutil/trace.go:171","msg":"trace[1297763239] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"355.264902ms","start":"2026-05-21T18:50:28.024168Z","end":"2026-05-21T18:50:28.379433Z","steps":["trace[1297763239] 'read index received' (duration: 53.882µs)","trace[1297763239] 'applied index is now lower than readState.Index' (duration: 355.20994ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:50:28.379474Z","caller":"traceutil/trace.go:171","msg":"trace[1524529672] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"493.543016ms","start":"2026-05-21T18:50:27.885899Z","end":"2026-05-21T18:50:28.379442Z","steps":["trace[1524529672] 'process raft request' (duration: 78.418348ms)","trace[1524529672] 'compare' (duration: 414.79542ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:50:28.37953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.363514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:50:28.379559Z","caller":"traceutil/trace.go:171","msg":"trace[356401171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"355.434796ms","start":"2026-05-21T18:50:28.024115Z","end":"2026-05-21T18:50:28.37955Z","steps":["trace[356401171] 'agreement among raft nodes before linearized reading' (duration: 355.360074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:28.37959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:28.024095Z","time spent":"355.488497ms","remote":"127.0.0.1:34394","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-21T18:50:28.379605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:27.885872Z","time spent":"493.666369ms","remote":"127.0.0.1:34484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":852,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T18:50:29.009356Z","caller":"traceutil/trace.go:171","msg":"trace[342710990] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"110.669151ms","start":"2026-05-21T18:50:28.89866Z","end":"2026-05-21T18:50:29.00933Z","steps":["trace[342710990] 'read index received' (duration: 110.62452ms)","trace[342710990] 'applied index is now lower than readState.Index' (duration: 43.421µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:50:29.009405Z","caller":"traceutil/trace.go:171","msg":"trace[2135019048] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"159.620602ms","start":"2026-05-21T18:50:28.849758Z","end":"2026-05-21T18:50:29.009379Z","steps":["trace[2135019048] 'process raft request' (duration: 159.425206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:29.009592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.918659ms","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-21T18:50:29.009639Z","caller":"traceutil/trace.go:171","msg":"trace[1836829879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"111.020671ms","start":"2026-05-21T18:50:28.898607Z","end":"2026-05-21T18:50:29.009628Z","steps":["trace[1836829879] 'agreement among raft nodes before linearized reading' (duration: 110.846837ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.213068Z","caller":"traceutil/trace.go:171","msg":"trace[678971799] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"203.611712ms","start":"2026-05-21T18:50:29.009429Z","end":"2026-05-21T18:50:29.213041Z","steps":["trace[678971799] 'read index received' (duration: 152.378987ms)","trace[678971799] 'applied index is now lower than readState.Index' (duration: 51.231695ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:50:29.213139Z","caller":"traceutil/trace.go:171","msg":"trace[1887505167] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"260.341532ms","start":"2026-05-21T18:50:28.952732Z","end":"2026-05-21T18:50:29.213074Z","steps":["trace[1887505167] 'process raft request' (duration: 209.095096ms)","trace[1887505167] 'compare' (duration: 51.089582ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:50:29.213285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.389643ms","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-21T18:50:29.213339Z","caller":"traceutil/trace.go:171","msg":"trace[1811048621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"269.469874ms","start":"2026-05-21T18:50:28.943856Z","end":"2026-05-21T18:50:29.213326Z","steps":["trace[1811048621] 'agreement among raft nodes before linearized reading' (duration: 269.28992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:29.213284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.430204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T18:50:29.213504Z","caller":"traceutil/trace.go:171","msg":"trace[1753802354] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"269.6831ms","start":"2026-05-21T18:50:28.943797Z","end":"2026-05-21T18:50:29.21348Z","steps":["trace[1753802354] 'agreement among raft nodes before linearized reading' (duration: 269.340782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:29.213746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.796552ms","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-21T18:50:29.213855Z","caller":"traceutil/trace.go:171","msg":"trace[192778443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"269.952956ms","start":"2026-05-21T18:50:28.943881Z","end":"2026-05-21T18:50:29.213834Z","steps":["trace[192778443] 'agreement among raft nodes before linearized reading' (duration: 269.226289ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.226703Z","caller":"traceutil/trace.go:171","msg":"trace[1260263964] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"211.779802ms","start":"2026-05-21T18:50:29.0149Z","end":"2026-05-21T18:50:29.22668Z","steps":["trace[1260263964] 'process raft request' (duration: 211.639769ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.226883Z","caller":"traceutil/trace.go:171","msg":"trace[1678479118] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"206.539453ms","start":"2026-05-21T18:50:29.020315Z","end":"2026-05-21T18:50:29.226855Z","steps":["trace[1678479118] 'process raft request' (duration: 206.304858ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.36165Z","caller":"traceutil/trace.go:171","msg":"trace[78886097] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"188.075484ms","start":"2026-05-21T18:50:29.17354Z","end":"2026-05-21T18:50:29.361615Z","steps":["trace[78886097] 'process raft request' (duration: 163.780834ms)","trace[78886097] 'compare' (duration: 24.028704ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:50:29.361973Z","caller":"traceutil/trace.go:171","msg":"trace[1610112200] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"144.206007ms","start":"2026-05-21T18:50:29.217743Z","end":"2026-05-21T18:50:29.361949Z","steps":["trace[1610112200] 'process raft request' (duration: 143.945201ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.362135Z","caller":"traceutil/trace.go:171","msg":"trace[497640055] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"144.217667ms","start":"2026-05-21T18:50:29.217898Z","end":"2026-05-21T18:50:29.362115Z","steps":["trace[497640055] 'process raft request' (duration: 143.865089ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.362512Z","caller":"traceutil/trace.go:171","msg":"trace[1889056247] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"144.466784ms","start":"2026-05-21T18:50:29.218032Z","end":"2026-05-21T18:50:29.362498Z","steps":["trace[1889056247] 'process raft request' (duration: 143.89618ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.362961Z","caller":"traceutil/trace.go:171","msg":"trace[451169261] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"139.939334ms","start":"2026-05-21T18:50:29.223009Z","end":"2026-05-21T18:50:29.362948Z","steps":["trace[451169261] 'process raft request' (duration: 139.889223ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.363205Z","caller":"traceutil/trace.go:171","msg":"trace[1023568957] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"144.206128ms","start":"2026-05-21T18:50:29.218988Z","end":"2026-05-21T18:50:29.363195Z","steps":["trace[1023568957] 'process raft request' (duration: 143.08807ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.531593Z","caller":"traceutil/trace.go:171","msg":"trace[1606804380] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"163.288922ms","start":"2026-05-21T18:50:29.368273Z","end":"2026-05-21T18:50:29.531562Z","steps":["trace[1606804380] 'process raft request' (duration: 162.836241ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.531634Z","caller":"traceutil/trace.go:171","msg":"trace[1101362966] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"118.098093ms","start":"2026-05-21T18:50:29.413508Z","end":"2026-05-21T18:50:29.531606Z","steps":["trace[1101362966] 'process raft request' (duration: 118.047322ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.531743Z","caller":"traceutil/trace.go:171","msg":"trace[999188885] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"162.754599ms","start":"2026-05-21T18:50:29.368976Z","end":"2026-05-21T18:50:29.531731Z","steps":["trace[999188885] 'process raft request' (duration: 162.525993ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.715682Z","caller":"traceutil/trace.go:171","msg":"trace[608443658] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"176.3571ms","start":"2026-05-21T18:50:29.539301Z","end":"2026-05-21T18:50:29.715658Z","steps":["trace[608443658] 'process raft request' (duration: 176.070683ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.798342Z","caller":"traceutil/trace.go:171","msg":"trace[732557251] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:679; }","duration":"247.761536ms","start":"2026-05-21T18:50:29.550557Z","end":"2026-05-21T18:50:29.798318Z","steps":["trace[732557251] 'read index received' (duration: 164.818169ms)","trace[732557251] 'applied index is now lower than readState.Index' (duration: 82.941757ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:50:29.798579Z","caller":"traceutil/trace.go:171","msg":"trace[763510974] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"259.257866ms","start":"2026-05-21T18:50:29.539299Z","end":"2026-05-21T18:50:29.798557Z","steps":["trace[763510974] 'process raft request' (duration: 240.019567ms)","trace[763510974] 'compare' (duration: 18.778438ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:50:29.798614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.004203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:50:29.798758Z","caller":"traceutil/trace.go:171","msg":"trace[690166810] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:654; }","duration":"248.232067ms","start":"2026-05-21T18:50:29.550512Z","end":"2026-05-21T18:50:29.798745Z","steps":["trace[690166810] 'agreement among raft nodes before linearized reading' (duration: 247.997542ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.946396Z","caller":"traceutil/trace.go:171","msg":"trace[1393365373] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"143.366547ms","start":"2026-05-21T18:50:29.803006Z","end":"2026-05-21T18:50:29.946372Z","steps":["trace[1393365373] 'read index received' (duration: 102.235636ms)","trace[1393365373] 'applied index is now lower than readState.Index' (duration: 41.129541ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:50:29.946505Z","caller":"traceutil/trace.go:171","msg":"trace[1856370561] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"190.503884ms","start":"2026-05-21T18:50:29.755962Z","end":"2026-05-21T18:50:29.946466Z","steps":["trace[1856370561] 'process raft request' (duration: 149.179358ms)","trace[1856370561] 'compare' (duration: 41.108251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:50:29.946673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.643774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ct97l\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-05-21T18:50:29.946715Z","caller":"traceutil/trace.go:171","msg":"trace[999941524] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ct97l; range_end:; response_count:1; response_revision:655; }","duration":"143.740586ms","start":"2026-05-21T18:50:29.802964Z","end":"2026-05-21T18:50:29.946705Z","steps":["trace[999941524] 'agreement among raft nodes before linearized reading' (duration: 143.51627ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.97386Z","caller":"traceutil/trace.go:171","msg":"trace[1370514249] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"164.47554ms","start":"2026-05-21T18:50:29.809353Z","end":"2026-05-21T18:50:29.973829Z","steps":["trace[1370514249] 'process raft request' (duration: 164.388268ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:29.97389Z","caller":"traceutil/trace.go:171","msg":"trace[1662830138] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"169.303978ms","start":"2026-05-21T18:50:29.804558Z","end":"2026-05-21T18:50:29.973862Z","steps":["trace[1662830138] 'process raft request' (duration: 169.066273ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:32.1363Z","caller":"traceutil/trace.go:171","msg":"trace[1580269566] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"112.390764ms","start":"2026-05-21T18:50:32.023886Z","end":"2026-05-21T18:50:32.136276Z","steps":["trace[1580269566] 'read index received' (duration: 112.169548ms)","trace[1580269566] 'applied index is now lower than readState.Index' (duration: 220.256µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:50:32.136407Z","caller":"traceutil/trace.go:171","msg":"trace[292023843] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"153.118384ms","start":"2026-05-21T18:50:31.983232Z","end":"2026-05-21T18:50:32.13635Z","steps":["trace[292023843] 'process raft request' (duration: 152.887369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:32.136474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.563008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:50:32.136521Z","caller":"traceutil/trace.go:171","msg":"trace[790995624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"112.64721ms","start":"2026-05-21T18:50:32.023851Z","end":"2026-05-21T18:50:32.136498Z","steps":["trace[790995624] 'agreement among raft nodes before linearized reading' (duration: 112.517527ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:37.208614Z","caller":"traceutil/trace.go:171","msg":"trace[1953274177] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:746; }","duration":"184.076497ms","start":"2026-05-21T18:50:37.024515Z","end":"2026-05-21T18:50:37.208591Z","steps":["trace[1953274177] 'read index received' (duration: 52.596719ms)","trace[1953274177] 'applied index is now lower than readState.Index' (duration: 131.478828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:50:37.208757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.21855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:50:37.20879Z","caller":"traceutil/trace.go:171","msg":"trace[1585905044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"184.300932ms","start":"2026-05-21T18:50:37.024478Z","end":"2026-05-21T18:50:37.208779Z","steps":["trace[1585905044] 'agreement among raft nodes before linearized reading' (duration: 184.226091ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:37.208872Z","caller":"traceutil/trace.go:171","msg":"trace[1100707660] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"199.227085ms","start":"2026-05-21T18:50:37.009589Z","end":"2026-05-21T18:50:37.208816Z","steps":["trace[1100707660] 'process raft request' (duration: 198.854646ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:37.255317Z","caller":"traceutil/trace.go:171","msg":"trace[1092417480] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"171.967712ms","start":"2026-05-21T18:50:37.083325Z","end":"2026-05-21T18:50:37.255293Z","steps":["trace[1092417480] 'process raft request' (duration: 171.811198ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:37.255636Z","caller":"traceutil/trace.go:171","msg":"trace[1601214491] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"172.008573ms","start":"2026-05-21T18:50:37.083604Z","end":"2026-05-21T18:50:37.255612Z","steps":["trace[1601214491] 'process raft request' (duration: 171.650714ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:37.255869Z","caller":"traceutil/trace.go:171","msg":"trace[884804469] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"172.051145ms","start":"2026-05-21T18:50:37.0838Z","end":"2026-05-21T18:50:37.255851Z","steps":["trace[884804469] 'process raft request' (duration: 171.655155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:43.382692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199817288932961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T18:50:43.883969Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199817288932961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T18:50:44.060167Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.249957546s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T18:50:44.061143Z","caller":"traceutil/trace.go:171","msg":"trace[1118285415] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"1.251068525s","start":"2026-05-21T18:50:42.810051Z","end":"2026-05-21T18:50:44.061119Z","steps":["trace[1118285415] 'process raft request' (duration: 1.250935591s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:44.061305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:42.810019Z","time spent":"1.25121563s","remote":"127.0.0.1:34554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T18:50:44.064223Z","caller":"traceutil/trace.go:171","msg":"trace[1185621611] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:798; }","duration":"1.182270528s","start":"2026-05-21T18:50:42.881936Z","end":"2026-05-21T18:50:44.064206Z","steps":["trace[1185621611] 'read index received' (duration: 1.179075184s)","trace[1185621611] 'applied index is now lower than readState.Index' (duration: 3.194564ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:50:44.064407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182460093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jbcml\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-21T18:50:44.064448Z","caller":"traceutil/trace.go:171","msg":"trace[1376158078] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jbcml; range_end:; response_count:1; response_revision:768; }","duration":"1.182542795s","start":"2026-05-21T18:50:42.881894Z","end":"2026-05-21T18:50:44.064437Z","steps":["trace[1376158078] 'agreement among raft nodes before linearized reading' (duration: 1.182401252s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:44.064485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:42.881882Z","time spent":"1.182594736s","remote":"127.0.0.1:34558","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-jbcml\" "} {"level":"warn","ts":"2026-05-21T18:50:44.064549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.822971ms","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-21T18:50:44.064564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.067418ms","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-21T18:50:44.064624Z","caller":"traceutil/trace.go:171","msg":"trace[996424945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:768; }","duration":"639.930445ms","start":"2026-05-21T18:50:43.424672Z","end":"2026-05-21T18:50:44.064603Z","steps":["trace[996424945] 'agreement among raft nodes before linearized reading' (duration: 639.687978ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:44.064657Z","caller":"traceutil/trace.go:171","msg":"trace[351728944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"640.200722ms","start":"2026-05-21T18:50:43.424438Z","end":"2026-05-21T18:50:44.064639Z","steps":["trace[351728944] 'agreement among raft nodes before linearized reading' (duration: 639.989046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:44.064685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:43.424659Z","time spent":"640.011496ms","remote":"127.0.0.1:34616","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-21T18:50:44.064549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.180271ms","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-21T18:50:44.064785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:43.424418Z","time spent":"640.353995ms","remote":"127.0.0.1:34616","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-21T18:50:44.064817Z","caller":"traceutil/trace.go:171","msg":"trace[166873829] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"640.446578ms","start":"2026-05-21T18:50:43.424312Z","end":"2026-05-21T18:50:44.064758Z","steps":["trace[166873829] 'agreement among raft nodes before linearized reading' (duration: 640.126609ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:50:44.064301Z","caller":"traceutil/trace.go:171","msg":"trace[1679940656] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"927.769117ms","start":"2026-05-21T18:50:43.136496Z","end":"2026-05-21T18:50:44.064266Z","steps":["trace[1679940656] 'process raft request' (duration: 927.630863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:44.064926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:43.424293Z","time spent":"640.597142ms","remote":"127.0.0.1:34616","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-21T18:50:44.064951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:43.136471Z","time spent":"928.427574ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:50:44.065372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.521687ms","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-21T18:50:44.06543Z","caller":"traceutil/trace.go:171","msg":"trace[1674984771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:768; }","duration":"770.62039ms","start":"2026-05-21T18:50:43.294795Z","end":"2026-05-21T18:50:44.065415Z","steps":["trace[1674984771] 'agreement among raft nodes before linearized reading' (duration: 769.536582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:44.067069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:50:43.294774Z","time spent":"771.111913ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T18:50:57.711132Z","caller":"traceutil/trace.go:171","msg":"trace[707844517] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"103.841174ms","start":"2026-05-21T18:50:57.607266Z","end":"2026-05-21T18:50:57.711107Z","steps":["trace[707844517] 'read index received' (duration: 103.555826ms)","trace[707844517] 'applied index is now lower than readState.Index' (duration: 283.978µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:50:57.711178Z","caller":"traceutil/trace.go:171","msg":"trace[586273597] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"146.297584ms","start":"2026-05-21T18:50:57.564846Z","end":"2026-05-21T18:50:57.711144Z","steps":["trace[586273597] 'process raft request' (duration: 146.070108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:50:57.711309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.019899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:50:57.711354Z","caller":"traceutil/trace.go:171","msg":"trace[1410438862] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:897; }","duration":"104.123862ms","start":"2026-05-21T18:50:57.607215Z","end":"2026-05-21T18:50:57.711339Z","steps":["trace[1410438862] 'agreement among raft nodes before linearized reading' (duration: 104.002478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:19.385162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.389747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T18:51:19.386095Z","caller":"traceutil/trace.go:171","msg":"trace[291764073] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"1.031207979s","start":"2026-05-21T18:51:18.354863Z","end":"2026-05-21T18:51:19.386071Z","steps":["trace[291764073] 'read index received' (duration: 358.634022ms)","trace[291764073] 'applied index is now lower than readState.Index' (duration: 672.571317ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:51:19.386188Z","caller":"traceutil/trace.go:171","msg":"trace[220593983] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.073812922s","start":"2026-05-21T18:51:18.312362Z","end":"2026-05-21T18:51:19.386175Z","steps":["trace[220593983] 'process raft request' (duration: 401.226355ms)","trace[220593983] 'compare' (duration: 671.057908ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:51:19.386278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:18.312345Z","time spent":"1.073873545s","remote":"127.0.0.1:34616","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-21T18:51:19.386398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031479117s","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-21T18:51:19.38653Z","caller":"traceutil/trace.go:171","msg":"trace[637223927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:980; }","duration":"1.031689932s","start":"2026-05-21T18:51:18.354812Z","end":"2026-05-21T18:51:19.386502Z","steps":["trace[637223927] 'agreement among raft nodes before linearized reading' (duration: 1.031366674s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:19.386587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:18.354788Z","time spent":"1.031789534s","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T18:51:19.886349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199817288933481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T18:51:20.215234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.484304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T18:51:20.215437Z","caller":"traceutil/trace.go:171","msg":"trace[289092174] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1020; }","duration":"829.291732ms","start":"2026-05-21T18:51:19.386127Z","end":"2026-05-21T18:51:20.215419Z","steps":["trace[289092174] 'read index received' (duration: 602.114166ms)","trace[289092174] 'applied index is now lower than readState.Index' (duration: 227.176186ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:51:20.21551Z","caller":"traceutil/trace.go:171","msg":"trace[665225152] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"823.886701ms","start":"2026-05-21T18:51:19.391568Z","end":"2026-05-21T18:51:20.215455Z","steps":["trace[665225152] 'process raft request' (duration: 823.799889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:20.215754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:19.391542Z","time spent":"824.077906ms","remote":"127.0.0.1:34616","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-21T18:51:20.216001Z","caller":"traceutil/trace.go:171","msg":"trace[1601560965] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"987.187609ms","start":"2026-05-21T18:51:19.228791Z","end":"2026-05-21T18:51:20.215979Z","steps":["trace[1601560965] 'process raft request' (duration: 758.860162ms)","trace[1601560965] 'compare' (duration: 226.783946ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:51:20.216044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.599713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T18:51:20.216079Z","caller":"traceutil/trace.go:171","msg":"trace[1321719418] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:982; }","duration":"867.677905ms","start":"2026-05-21T18:51:19.348389Z","end":"2026-05-21T18:51:20.216067Z","steps":["trace[1321719418] 'agreement among raft nodes before linearized reading' (duration: 867.12077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:20.216073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:19.228756Z","time spent":"987.277091ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:51:20.215943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.854605008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-21T18:51:20.216113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:19.348359Z","time spent":"867.746277ms","remote":"127.0.0.1:34774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-21T18:51:20.216151Z","caller":"traceutil/trace.go:171","msg":"trace[1311285210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:982; }","duration":"1.854876766s","start":"2026-05-21T18:51:18.361247Z","end":"2026-05-21T18:51:20.216124Z","steps":["trace[1311285210] 'agreement among raft nodes before linearized reading' (duration: 1.854415683s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:20.216205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:18.361217Z","time spent":"1.854975708s","remote":"127.0.0.1:34616","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-21T18:51:20.216376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191369795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:51:20.21641Z","caller":"traceutil/trace.go:171","msg":"trace[2005828585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"1.191431077s","start":"2026-05-21T18:51:19.024967Z","end":"2026-05-21T18:51:20.216398Z","steps":["trace[2005828585] 'agreement among raft nodes before linearized reading' (duration: 1.191380945s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:20.216438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:19.02495Z","time spent":"1.191482199s","remote":"127.0.0.1:34394","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-21T18:51:20.216518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.909916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-21T18:51:20.216603Z","caller":"traceutil/trace.go:171","msg":"trace[1462558504] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:982; }","duration":"285.045599ms","start":"2026-05-21T18:51:19.931534Z","end":"2026-05-21T18:51:20.21658Z","steps":["trace[1462558504] 'agreement among raft nodes before linearized reading' (duration: 284.799873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:20.217078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253458607s","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-21T18:51:20.21712Z","caller":"traceutil/trace.go:171","msg":"trace[794236123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:982; }","duration":"1.253503928s","start":"2026-05-21T18:51:18.963605Z","end":"2026-05-21T18:51:20.217109Z","steps":["trace[794236123] 'agreement among raft nodes before linearized reading' (duration: 1.253382345s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:20.217153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:18.963561Z","time spent":"1.253584721s","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T18:51:20.641057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:20.218792Z","time spent":"422.260754ms","remote":"127.0.0.1:34438","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T18:51:20.64817Z","caller":"traceutil/trace.go:171","msg":"trace[957303597] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"425.165992ms","start":"2026-05-21T18:51:20.222983Z","end":"2026-05-21T18:51:20.648149Z","steps":["trace[957303597] 'process raft request' (duration: 425.000308ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:51:20.648271Z","caller":"traceutil/trace.go:171","msg":"trace[567739338] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1022; }","duration":"421.794793ms","start":"2026-05-21T18:51:20.22644Z","end":"2026-05-21T18:51:20.648235Z","steps":["trace[567739338] 'read index received' (duration: 413.660881ms)","trace[567739338] 'applied index is now lower than readState.Index' (duration: 8.131872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:51:20.648418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.968139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:51:20.648456Z","caller":"traceutil/trace.go:171","msg":"trace[118011614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"422.02062ms","start":"2026-05-21T18:51:20.226424Z","end":"2026-05-21T18:51:20.648445Z","steps":["trace[118011614] 'agreement among raft nodes before linearized reading' (duration: 421.953148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:20.648482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:20.226418Z","time spent":"422.058361ms","remote":"127.0.0.1:34394","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-21T18:51:20.648274Z","caller":"traceutil/trace.go:171","msg":"trace[2116546832] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"421.674621ms","start":"2026-05-21T18:51:20.226572Z","end":"2026-05-21T18:51:20.648247Z","steps":["trace[2116546832] 'process raft request' (duration: 421.539467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:20.648914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:20.226568Z","time spent":"422.301157ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:51:20.649121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.177789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T18:51:20.649181Z","caller":"traceutil/trace.go:171","msg":"trace[1033767168] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:985; }","duration":"379.304323ms","start":"2026-05-21T18:51:20.269864Z","end":"2026-05-21T18:51:20.649168Z","steps":["trace[1033767168] 'agreement among raft nodes before linearized reading' (duration: 379.161139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:20.649213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:20.269834Z","time spent":"379.372335ms","remote":"127.0.0.1:34804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-21T18:51:20.649262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.018324ms","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-21T18:51:20.649303Z","caller":"traceutil/trace.go:171","msg":"trace[1442962584] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:985; }","duration":"198.115007ms","start":"2026-05-21T18:51:20.451174Z","end":"2026-05-21T18:51:20.649289Z","steps":["trace[1442962584] 'agreement among raft nodes before linearized reading' (duration: 198.031594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:20.649407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.517938ms","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-21T18:51:20.649521Z","caller":"traceutil/trace.go:171","msg":"trace[1695336260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:985; }","duration":"304.696273ms","start":"2026-05-21T18:51:20.3448Z","end":"2026-05-21T18:51:20.649497Z","steps":["trace[1695336260] 'agreement among raft nodes before linearized reading' (duration: 304.418686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:20.649578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:20.344765Z","time spent":"304.794905ms","remote":"127.0.0.1:34616","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-21T18:51:20.649617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:20.222965Z","time spent":"425.363567ms","remote":"127.0.0.1:34616","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-21T18:51:29.541741Z","caller":"traceutil/trace.go:171","msg":"trace[1682563777] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"101.231146ms","start":"2026-05-21T18:51:29.440484Z","end":"2026-05-21T18:51:29.541715Z","steps":["trace[1682563777] 'process raft request' (duration: 101.063361ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:51:32.923964Z","caller":"traceutil/trace.go:171","msg":"trace[582585470] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"112.451749ms","start":"2026-05-21T18:51:32.811484Z","end":"2026-05-21T18:51:32.923935Z","steps":["trace[582585470] 'process raft request' (duration: 87.860966ms)","trace[582585470] 'compare' (duration: 24.423889ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:51:32.924158Z","caller":"traceutil/trace.go:171","msg":"trace[823710189] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"110.901599ms","start":"2026-05-21T18:51:32.813225Z","end":"2026-05-21T18:51:32.924127Z","steps":["trace[823710189] 'process raft request' (duration: 110.670333ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:51:35.121523Z","caller":"traceutil/trace.go:171","msg":"trace[1581195993] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"189.429119ms","start":"2026-05-21T18:51:34.932054Z","end":"2026-05-21T18:51:35.121483Z","steps":["trace[1581195993] 'process raft request' (duration: 189.170423ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:51:35.125616Z","caller":"traceutil/trace.go:171","msg":"trace[1015038586] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"193.264201ms","start":"2026-05-21T18:51:34.932333Z","end":"2026-05-21T18:51:35.125597Z","steps":["trace[1015038586] 'process raft request' (duration: 193.118607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:35.126001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.201437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:51:35.12606Z","caller":"traceutil/trace.go:171","msg":"trace[285146462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1033; }","duration":"103.334671ms","start":"2026-05-21T18:51:35.022711Z","end":"2026-05-21T18:51:35.126046Z","steps":["trace[285146462] 'agreement among raft nodes before linearized reading' (duration: 103.232179ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:51:35.126791Z","caller":"traceutil/trace.go:171","msg":"trace[1702728087] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1073; }","duration":"103.011643ms","start":"2026-05-21T18:51:35.02278Z","end":"2026-05-21T18:51:35.125792Z","steps":["trace[1702728087] 'read index received' (duration: 98.500364ms)","trace[1702728087] 'applied index is now lower than readState.Index' (duration: 4.509179ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:51:37.440666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.015793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T18:51:37.440763Z","caller":"traceutil/trace.go:171","msg":"trace[1034641238] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1039; }","duration":"121.175656ms","start":"2026-05-21T18:51:37.319567Z","end":"2026-05-21T18:51:37.440743Z","steps":["trace[1034641238] 'count revisions from in-memory index tree' (duration: 120.92248ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:51:46.795189Z","caller":"traceutil/trace.go:171","msg":"trace[667987155] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1113; }","duration":"296.265505ms","start":"2026-05-21T18:51:46.498885Z","end":"2026-05-21T18:51:46.795151Z","steps":["trace[667987155] 'read index received' (duration: 295.959567ms)","trace[667987155] 'applied index is now lower than readState.Index' (duration: 304.788µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:51:46.795348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.428789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:51:46.795391Z","caller":"traceutil/trace.go:171","msg":"trace[1346807483] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1068; }","duration":"296.531302ms","start":"2026-05-21T18:51:46.498844Z","end":"2026-05-21T18:51:46.795376Z","steps":["trace[1346807483] 'agreement among raft nodes before linearized reading' (duration: 296.427279ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:51:46.795411Z","caller":"traceutil/trace.go:171","msg":"trace[794625374] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"341.069393ms","start":"2026-05-21T18:51:46.454271Z","end":"2026-05-21T18:51:46.79534Z","steps":["trace[794625374] 'process raft request' (duration: 340.671273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:46.795929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:46.454252Z","time spent":"341.34226ms","remote":"127.0.0.1:34616","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-21T18:51:46.811762Z","caller":"traceutil/trace.go:171","msg":"trace[458161993] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"310.482886ms","start":"2026-05-21T18:51:46.50126Z","end":"2026-05-21T18:51:46.811743Z","steps":["trace[458161993] 'process raft request' (duration: 310.324982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:46.81187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:46.501245Z","time spent":"310.569468ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:51:48.164573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.199967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:51:48.164716Z","caller":"traceutil/trace.go:171","msg":"trace[1941455114] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1073; }","duration":"157.431283ms","start":"2026-05-21T18:51:48.007267Z","end":"2026-05-21T18:51:48.164698Z","steps":["trace[1941455114] 'count revisions from in-memory index tree' (duration: 156.994352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:48.16482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.407356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T18:51:48.164965Z","caller":"traceutil/trace.go:171","msg":"trace[1660949095] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1073; }","duration":"196.623602ms","start":"2026-05-21T18:51:47.968319Z","end":"2026-05-21T18:51:48.164942Z","steps":["trace[1660949095] 'count revisions from in-memory index tree' (duration: 196.254023ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:51:49.125812Z","caller":"traceutil/trace.go:171","msg":"trace[1063122159] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"321.357595ms","start":"2026-05-21T18:51:48.804416Z","end":"2026-05-21T18:51:49.125773Z","steps":["trace[1063122159] 'process raft request' (duration: 321.15486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:49.12606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:48.804315Z","time spent":"321.608081ms","remote":"127.0.0.1:34616","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-21T18:51:49.135689Z","caller":"traceutil/trace.go:171","msg":"trace[339699222] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1119; }","duration":"111.448206ms","start":"2026-05-21T18:51:49.024228Z","end":"2026-05-21T18:51:49.135677Z","steps":["trace[339699222] 'read index received' (duration: 101.250173ms)","trace[339699222] 'applied index is now lower than readState.Index' (duration: 10.196883ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:51:49.13589Z","caller":"traceutil/trace.go:171","msg":"trace[1596646969] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"317.957351ms","start":"2026-05-21T18:51:48.817921Z","end":"2026-05-21T18:51:49.135879Z","steps":["trace[1596646969] 'process raft request' (duration: 317.696874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:51:49.135947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:51:48.817889Z","time spent":"318.024504ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:51:49.136078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.858795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:51:49.136164Z","caller":"traceutil/trace.go:171","msg":"trace[1275025311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1075; }","duration":"111.981038ms","start":"2026-05-21T18:51:49.024174Z","end":"2026-05-21T18:51:49.136155Z","steps":["trace[1275025311] 'agreement among raft nodes before linearized reading' (duration: 111.879416ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:51:49.300811Z","caller":"traceutil/trace.go:171","msg":"trace[392713204] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"161.229197ms","start":"2026-05-21T18:51:49.139549Z","end":"2026-05-21T18:51:49.300778Z","steps":["trace[392713204] 'process raft request' (duration: 160.185381ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:51:49.492272Z","caller":"traceutil/trace.go:171","msg":"trace[1955226295] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"119.250459ms","start":"2026-05-21T18:51:49.372984Z","end":"2026-05-21T18:51:49.492234Z","steps":["trace[1955226295] 'process raft request' (duration: 26.848754ms)","trace[1955226295] 'compare' (duration: 92.177079ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:51:49.942035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.104657ms","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-21T18:51:49.942127Z","caller":"traceutil/trace.go:171","msg":"trace[1576068300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1078; }","duration":"166.260922ms","start":"2026-05-21T18:51:49.775846Z","end":"2026-05-21T18:51:49.942107Z","steps":["trace[1576068300] 'range keys from in-memory index tree' (duration: 165.898602ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:51:50.151299Z","caller":"traceutil/trace.go:171","msg":"trace[1100710763] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"203.543992ms","start":"2026-05-21T18:51:49.947721Z","end":"2026-05-21T18:51:50.151265Z","steps":["trace[1100710763] 'process raft request' (duration: 192.810547ms)","trace[1100710763] 'compare' (duration: 10.578251ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:51:50.15685Z","caller":"traceutil/trace.go:171","msg":"trace[65408036] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"156.479929ms","start":"2026-05-21T18:51:50.000352Z","end":"2026-05-21T18:51:50.156832Z","steps":["trace[65408036] 'process raft request' (duration: 156.364826ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:06.394358Z","caller":"traceutil/trace.go:171","msg":"trace[1179735179] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"166.885977ms","start":"2026-05-21T18:52:06.227444Z","end":"2026-05-21T18:52:06.39433Z","steps":["trace[1179735179] 'process raft request' (duration: 166.742953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:07.804396Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199817288933974,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T18:52:08.016961Z","caller":"traceutil/trace.go:171","msg":"trace[526860806] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"717.805447ms","start":"2026-05-21T18:52:07.299115Z","end":"2026-05-21T18:52:08.01692Z","steps":["trace[526860806] 'process raft request' (duration: 717.621423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:08.017174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:07.299076Z","time spent":"717.983782ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:52:08.017201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.929532ms","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-21T18:52:08.017253Z","caller":"traceutil/trace.go:171","msg":"trace[684439461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1186; }","duration":"714.020844ms","start":"2026-05-21T18:52:07.303219Z","end":"2026-05-21T18:52:08.017239Z","steps":["trace[684439461] 'agreement among raft nodes before linearized reading' (duration: 713.782808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:08.017304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:07.303174Z","time spent":"714.122166ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T18:52:08.018762Z","caller":"traceutil/trace.go:171","msg":"trace[562682203] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"713.627365ms","start":"2026-05-21T18:52:07.303267Z","end":"2026-05-21T18:52:08.016895Z","steps":["trace[562682203] 'read index received' (duration: 713.45521ms)","trace[562682203] 'applied index is now lower than readState.Index' (duration: 170.865µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:52:08.018884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.506633ms","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-21T18:52:08.018908Z","caller":"traceutil/trace.go:171","msg":"trace[1830931584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1186; }","duration":"622.575515ms","start":"2026-05-21T18:52:07.396324Z","end":"2026-05-21T18:52:08.0189Z","steps":["trace[1830931584] 'agreement among raft nodes before linearized reading' (duration: 622.506783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:08.018922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:07.396266Z","time spent":"622.652816ms","remote":"127.0.0.1:34616","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-21T18:52:08.018965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.836004ms","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-21T18:52:08.019007Z","caller":"traceutil/trace.go:171","msg":"trace[2120110427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1186; }","duration":"572.926116ms","start":"2026-05-21T18:52:07.446068Z","end":"2026-05-21T18:52:08.018994Z","steps":["trace[2120110427] 'agreement among raft nodes before linearized reading' (duration: 572.812773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:08.019038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:07.446044Z","time spent":"572.987008ms","remote":"127.0.0.1:34616","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-21T18:52:08.019021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.935329ms","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-21T18:52:08.019098Z","caller":"traceutil/trace.go:171","msg":"trace[1197748564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1186; }","duration":"345.070472ms","start":"2026-05-21T18:52:07.674007Z","end":"2026-05-21T18:52:08.019077Z","steps":["trace[1197748564] 'agreement among raft nodes before linearized reading' (duration: 344.845836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:08.019133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:07.673982Z","time spent":"345.143494ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T18:52:08.523892Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199817288933978,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T18:52:09.024965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199817288933978,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T18:52:09.176666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:08.382224Z","time spent":"794.434453ms","remote":"127.0.0.1:54526","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T18:52:09.177347Z","caller":"traceutil/trace.go:171","msg":"trace[1288520526] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"1.15510284s","start":"2026-05-21T18:52:08.022217Z","end":"2026-05-21T18:52:09.17732Z","steps":["trace[1288520526] 'process raft request' (duration: 1.153287075s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:09.177533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:08.022197Z","time spent":"1.155216062s","remote":"127.0.0.1:34616","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-21T18:52:09.184141Z","caller":"traceutil/trace.go:171","msg":"trace[261786412] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1238; }","duration":"1.160492183s","start":"2026-05-21T18:52:08.023628Z","end":"2026-05-21T18:52:09.18412Z","steps":["trace[261786412] 'read index received' (duration: 1.15188996s)","trace[261786412] 'applied index is now lower than readState.Index' (duration: 8.601263ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:52:09.18435Z","caller":"traceutil/trace.go:171","msg":"trace[55572147] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"1.160664058s","start":"2026-05-21T18:52:08.023676Z","end":"2026-05-21T18:52:09.18434Z","steps":["trace[55572147] 'process raft request' (duration: 1.160289499s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:09.184451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:08.023665Z","time spent":"1.16071378s","remote":"127.0.0.1:34616","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-21T18:52:09.184538Z","caller":"traceutil/trace.go:171","msg":"trace[46456431] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"1.160823511s","start":"2026-05-21T18:52:08.023709Z","end":"2026-05-21T18:52:09.184532Z","steps":["trace[46456431] 'process raft request' (duration: 1.1603548s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:09.184575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:08.023694Z","time spent":"1.160861753s","remote":"127.0.0.1:34616","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-21T18:52:09.184641Z","caller":"traceutil/trace.go:171","msg":"trace[1279639588] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"1.160153075s","start":"2026-05-21T18:52:08.024477Z","end":"2026-05-21T18:52:09.18463Z","steps":["trace[1279639588] 'process raft request' (duration: 1.159611652s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:09.184706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:08.024467Z","time spent":"1.160209816s","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:52:09.184793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16116019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:52:09.184826Z","caller":"traceutil/trace.go:171","msg":"trace[1997134978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1190; }","duration":"1.161199681s","start":"2026-05-21T18:52:08.023615Z","end":"2026-05-21T18:52:09.184815Z","steps":["trace[1997134978] 'agreement among raft nodes before linearized reading' (duration: 1.16115099s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:09.184853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:08.023602Z","time spent":"1.161245632s","remote":"127.0.0.1:34394","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-21T18:52:09.185037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.045161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T18:52:09.185108Z","caller":"traceutil/trace.go:171","msg":"trace[1373416457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1190; }","duration":"783.183034ms","start":"2026-05-21T18:52:08.401908Z","end":"2026-05-21T18:52:09.185091Z","steps":["trace[1373416457] 'agreement among raft nodes before linearized reading' (duration: 782.982659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:09.185141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:08.401873Z","time spent":"783.262346ms","remote":"127.0.0.1:34616","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-21T18:52:09.185175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.438763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T18:52:09.185209Z","caller":"traceutil/trace.go:171","msg":"trace[876625779] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1190; }","duration":"284.477004ms","start":"2026-05-21T18:52:08.900725Z","end":"2026-05-21T18:52:09.185202Z","steps":["trace[876625779] 'agreement among raft nodes before linearized reading' (duration: 284.410252ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:19.457244Z","caller":"traceutil/trace.go:171","msg":"trace[990226631] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"125.240387ms","start":"2026-05-21T18:52:19.331969Z","end":"2026-05-21T18:52:19.45721Z","steps":["trace[990226631] 'process raft request' (duration: 120.019718ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:20.230375Z","caller":"traceutil/trace.go:171","msg":"trace[437518596] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"222.174484ms","start":"2026-05-21T18:52:20.008156Z","end":"2026-05-21T18:52:20.230331Z","steps":["trace[437518596] 'process raft request' (duration: 135.728176ms)","trace[437518596] 'compare' (duration: 86.281514ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:52:20.230409Z","caller":"traceutil/trace.go:171","msg":"trace[20774117] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"145.694072ms","start":"2026-05-21T18:52:20.084696Z","end":"2026-05-21T18:52:20.23039Z","steps":["trace[20774117] 'process raft request' (duration: 145.61865ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:20.230458Z","caller":"traceutil/trace.go:171","msg":"trace[123040038] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1279; }","duration":"206.295341ms","start":"2026-05-21T18:52:20.024123Z","end":"2026-05-21T18:52:20.230418Z","steps":["trace[123040038] 'read index received' (duration: 119.777492ms)","trace[123040038] 'applied index is now lower than readState.Index' (duration: 86.515049ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:52:20.230617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.473196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:52:20.230757Z","caller":"traceutil/trace.go:171","msg":"trace[154465377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1226; }","duration":"206.698171ms","start":"2026-05-21T18:52:20.024049Z","end":"2026-05-21T18:52:20.230748Z","steps":["trace[154465377] 'agreement among raft nodes before linearized reading' (duration: 206.478325ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:22.017034Z","caller":"traceutil/trace.go:171","msg":"trace[1878026627] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"332.149681ms","start":"2026-05-21T18:52:21.684852Z","end":"2026-05-21T18:52:22.017001Z","steps":["trace[1878026627] 'process raft request' (duration: 331.953716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:22.017238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:21.68483Z","time spent":"332.293886ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T18:52:24.367501Z","caller":"traceutil/trace.go:171","msg":"trace[1167782321] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"125.064743ms","start":"2026-05-21T18:52:24.242411Z","end":"2026-05-21T18:52:24.367476Z","steps":["trace[1167782321] 'process raft request' (duration: 124.903798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:24.782917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.48281ms","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-21T18:52:24.783189Z","caller":"traceutil/trace.go:171","msg":"trace[1268565315] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1247; }","duration":"258.768187ms","start":"2026-05-21T18:52:24.524391Z","end":"2026-05-21T18:52:24.783159Z","steps":["trace[1268565315] 'range keys from in-memory index tree' (duration: 258.46181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:25.8931Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199817288934127,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T18:52:26.006919Z","caller":"traceutil/trace.go:171","msg":"trace[1265009140] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"623.874196ms","start":"2026-05-21T18:52:25.383008Z","end":"2026-05-21T18:52:26.006882Z","steps":["trace[1265009140] 'process raft request' (duration: 623.511217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:26.0071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:25.382977Z","time spent":"624.030359ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:52:26.660695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.027972ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-21T18:52:26.661051Z","caller":"traceutil/trace.go:171","msg":"trace[165432264] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"288.606876ms","start":"2026-05-21T18:52:26.372423Z","end":"2026-05-21T18:52:26.66103Z","steps":["trace[165432264] 'process raft request' (duration: 288.558135ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:26.661241Z","caller":"traceutil/trace.go:171","msg":"trace[937093415] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1302; }","duration":"1.268460622s","start":"2026-05-21T18:52:25.392749Z","end":"2026-05-21T18:52:26.66121Z","steps":["trace[937093415] 'read index received' (duration: 613.682593ms)","trace[937093415] 'applied index is now lower than readState.Index' (duration: 654.776678ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:52:26.661551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26878204s","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-21T18:52:26.661696Z","caller":"traceutil/trace.go:171","msg":"trace[206082656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1250; }","duration":"1.268981786s","start":"2026-05-21T18:52:25.392684Z","end":"2026-05-21T18:52:26.661666Z","steps":["trace[206082656] 'agreement among raft nodes before linearized reading' (duration: 1.268694409s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:26.661817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:25.392657Z","time spent":"1.26914496s","remote":"127.0.0.1:34616","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-21T18:52:26.661302Z","caller":"traceutil/trace.go:171","msg":"trace[1451219876] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"467.597931ms","start":"2026-05-21T18:52:26.193676Z","end":"2026-05-21T18:52:26.661274Z","steps":["trace[1451219876] 'process raft request' (duration: 467.16335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:26.662034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:26.193642Z","time spent":"468.299909ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:52:26.669974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271538158s","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-21T18:52:26.670016Z","caller":"traceutil/trace.go:171","msg":"trace[1586794636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1251; }","duration":"1.27161025s","start":"2026-05-21T18:52:25.398395Z","end":"2026-05-21T18:52:26.670006Z","steps":["trace[1586794636] 'agreement among raft nodes before linearized reading' (duration: 1.271464336s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:26.669991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.76983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T18:52:26.67004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:25.398385Z","time spent":"1.271650441s","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T18:52:26.670074Z","caller":"traceutil/trace.go:171","msg":"trace[1428924357] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1251; }","duration":"762.931233ms","start":"2026-05-21T18:52:25.907121Z","end":"2026-05-21T18:52:26.670052Z","steps":["trace[1428924357] 'agreement among raft nodes before linearized reading' (duration: 762.761979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:26.670133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:25.907094Z","time spent":"763.026096ms","remote":"127.0.0.1:34740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T18:52:26.6701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187918021s","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-21T18:52:26.670241Z","caller":"traceutil/trace.go:171","msg":"trace[1384639535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1251; }","duration":"1.188112027s","start":"2026-05-21T18:52:25.482113Z","end":"2026-05-21T18:52:26.670225Z","steps":["trace[1384639535] 'agreement among raft nodes before linearized reading' (duration: 1.187794189s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:26.670186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271032227s","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-21T18:52:26.670313Z","caller":"traceutil/trace.go:171","msg":"trace[192368671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1251; }","duration":"1.27117417s","start":"2026-05-21T18:52:25.399132Z","end":"2026-05-21T18:52:26.670306Z","steps":["trace[192368671] 'agreement among raft nodes before linearized reading' (duration: 1.271012036s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:26.670341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:25.399109Z","time spent":"1.271225621s","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T18:52:26.670386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:25.482089Z","time spent":"1.188203939s","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T18:52:26.932916Z","caller":"traceutil/trace.go:171","msg":"trace[2009763642] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"258.580513ms","start":"2026-05-21T18:52:26.674313Z","end":"2026-05-21T18:52:26.932894Z","steps":["trace[2009763642] 'process raft request' (duration: 258.551172ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:26.933139Z","caller":"traceutil/trace.go:171","msg":"trace[483806640] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"258.805099ms","start":"2026-05-21T18:52:26.674298Z","end":"2026-05-21T18:52:26.933103Z","steps":["trace[483806640] 'process raft request' (duration: 258.541543ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:26.933103Z","caller":"traceutil/trace.go:171","msg":"trace[1722045134] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"258.900651ms","start":"2026-05-21T18:52:26.674172Z","end":"2026-05-21T18:52:26.933072Z","steps":["trace[1722045134] 'process raft request' (duration: 258.547663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:28.049718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.516315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:52:28.049822Z","caller":"traceutil/trace.go:171","msg":"trace[936951061] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1256; }","duration":"612.677939ms","start":"2026-05-21T18:52:27.437114Z","end":"2026-05-21T18:52:28.049792Z","steps":["trace[936951061] 'count revisions from in-memory index tree' (duration: 612.407992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:28.049877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:27.437088Z","time spent":"612.760231ms","remote":"127.0.0.1:34548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-05-21T18:52:28.049942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.554916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-05-21T18:52:28.050038Z","caller":"traceutil/trace.go:171","msg":"trace[971269997] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1256; }","duration":"210.72899ms","start":"2026-05-21T18:52:27.839288Z","end":"2026-05-21T18:52:28.050017Z","steps":["trace[971269997] 'range keys from in-memory index tree' (duration: 210.363361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:28.05015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.488982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:52:28.050261Z","caller":"traceutil/trace.go:171","msg":"trace[725211959] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1256; }","duration":"199.660626ms","start":"2026-05-21T18:52:27.850575Z","end":"2026-05-21T18:52:28.050236Z","steps":["trace[725211959] 'count revisions from in-memory index tree' (duration: 199.39751ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:28.339706Z","caller":"traceutil/trace.go:171","msg":"trace[2007479858] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"284.791571ms","start":"2026-05-21T18:52:28.054887Z","end":"2026-05-21T18:52:28.339678Z","steps":["trace[2007479858] 'process raft request' (duration: 284.613267ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:29.122319Z","caller":"traceutil/trace.go:171","msg":"trace[1918401093] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"1.062765067s","start":"2026-05-21T18:52:28.059494Z","end":"2026-05-21T18:52:29.122259Z","steps":["trace[1918401093] 'process raft request' (duration: 1.06246584s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:29.122651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:28.05947Z","time spent":"1.062990043s","remote":"127.0.0.1:34554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T18:52:29.242197Z","caller":"traceutil/trace.go:171","msg":"trace[420737772] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1313; }","duration":"563.137844ms","start":"2026-05-21T18:52:28.679034Z","end":"2026-05-21T18:52:29.242172Z","steps":["trace[420737772] 'read index received' (duration: 443.030184ms)","trace[420737772] 'applied index is now lower than readState.Index' (duration: 120.106669ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:52:29.242443Z","caller":"traceutil/trace.go:171","msg":"trace[259110915] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"575.871669ms","start":"2026-05-21T18:52:28.666557Z","end":"2026-05-21T18:52:29.242429Z","steps":["trace[259110915] 'process raft request' (duration: 575.538341ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:29.242254Z","caller":"traceutil/trace.go:171","msg":"trace[2016030292] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"790.66892ms","start":"2026-05-21T18:52:28.451512Z","end":"2026-05-21T18:52:29.242181Z","steps":["trace[2016030292] 'process raft request' (duration: 738.826477ms)","trace[2016030292] 'compare' (duration: 51.575046ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:52:29.242638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:28.66653Z","time spent":"575.983182ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:52:29.242822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.849172ms","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-21T18:52:29.242843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.96991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:52:29.242886Z","caller":"traceutil/trace.go:171","msg":"trace[2091515829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1260; }","duration":"305.985806ms","start":"2026-05-21T18:52:28.936884Z","end":"2026-05-21T18:52:29.242869Z","steps":["trace[2091515829] 'agreement among raft nodes before linearized reading' (duration: 305.75706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:29.242898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.868332ms","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-21T18:52:29.242918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:28.936855Z","time spent":"306.056807ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T18:52:29.242912Z","caller":"traceutil/trace.go:171","msg":"trace[1796664314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"218.110193ms","start":"2026-05-21T18:52:29.024785Z","end":"2026-05-21T18:52:29.242895Z","steps":["trace[1796664314] 'agreement among raft nodes before linearized reading' (duration: 217.97577ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:29.242949Z","caller":"traceutil/trace.go:171","msg":"trace[562305919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1260; }","duration":"563.957193ms","start":"2026-05-21T18:52:28.678975Z","end":"2026-05-21T18:52:29.242932Z","steps":["trace[562305919] 'agreement among raft nodes before linearized reading' (duration: 563.731228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:29.242991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:28.678962Z","time spent":"564.022635ms","remote":"127.0.0.1:34616","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-21T18:52:29.243039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.3535ms","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-21T18:52:29.243057Z","caller":"traceutil/trace.go:171","msg":"trace[476177341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1260; }","duration":"305.398271ms","start":"2026-05-21T18:52:28.937653Z","end":"2026-05-21T18:52:29.243052Z","steps":["trace[476177341] 'agreement among raft nodes before linearized reading' (duration: 305.3508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:29.243072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:28.937641Z","time spent":"305.428011ms","remote":"127.0.0.1:34616","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-21T18:52:29.242651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:28.45148Z","time spent":"791.07683ms","remote":"127.0.0.1:34484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T18:52:29.243086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.545255ms","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-21T18:52:29.243242Z","caller":"traceutil/trace.go:171","msg":"trace[1754949700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1260; }","duration":"303.709169ms","start":"2026-05-21T18:52:28.939467Z","end":"2026-05-21T18:52:29.243176Z","steps":["trace[1754949700] 'agreement among raft nodes before linearized reading' (duration: 303.385671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:29.243371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:28.939449Z","time spent":"303.905063ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T18:52:29.243141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.2438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-21T18:52:29.243577Z","caller":"traceutil/trace.go:171","msg":"trace[1048601480] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1260; }","duration":"113.68284ms","start":"2026-05-21T18:52:29.129869Z","end":"2026-05-21T18:52:29.243552Z","steps":["trace[1048601480] 'agreement among raft nodes before linearized reading' (duration: 113.24983ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:29.452491Z","caller":"traceutil/trace.go:171","msg":"trace[1420360885] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1317; }","duration":"100.769662ms","start":"2026-05-21T18:52:29.351696Z","end":"2026-05-21T18:52:29.452466Z","steps":["trace[1420360885] 'read index received' (duration: 100.303461ms)","trace[1420360885] 'applied index is now lower than readState.Index' (duration: 465.111µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:52:29.452798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.069899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T18:52:29.452844Z","caller":"traceutil/trace.go:171","msg":"trace[794253171] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1266; }","duration":"101.198122ms","start":"2026-05-21T18:52:29.351635Z","end":"2026-05-21T18:52:29.452833Z","steps":["trace[794253171] 'agreement among raft nodes before linearized reading' (duration: 100.939736ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:29.453483Z","caller":"traceutil/trace.go:171","msg":"trace[261231422] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"204.186578ms","start":"2026-05-21T18:52:29.249278Z","end":"2026-05-21T18:52:29.453465Z","steps":["trace[261231422] 'process raft request' (duration: 202.810815ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:29.453743Z","caller":"traceutil/trace.go:171","msg":"trace[357842841] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"203.268315ms","start":"2026-05-21T18:52:29.250463Z","end":"2026-05-21T18:52:29.453732Z","steps":["trace[357842841] 'process raft request' (duration: 201.808299ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:29.453869Z","caller":"traceutil/trace.go:171","msg":"trace[1700484970] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"202.457235ms","start":"2026-05-21T18:52:29.251403Z","end":"2026-05-21T18:52:29.45386Z","steps":["trace[1700484970] 'process raft request' (duration: 200.938528ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:29.453997Z","caller":"traceutil/trace.go:171","msg":"trace[407673705] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"200.513347ms","start":"2026-05-21T18:52:29.253475Z","end":"2026-05-21T18:52:29.453988Z","steps":["trace[407673705] 'process raft request' (duration: 198.900828ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:29.454123Z","caller":"traceutil/trace.go:171","msg":"trace[729957792] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"200.21891ms","start":"2026-05-21T18:52:29.253895Z","end":"2026-05-21T18:52:29.454114Z","steps":["trace[729957792] 'process raft request' (duration: 198.534659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:30.544469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.917447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-21T18:52:30.54447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.213709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:52:30.544575Z","caller":"traceutil/trace.go:171","msg":"trace[874416184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1267; }","duration":"201.067201ms","start":"2026-05-21T18:52:30.343471Z","end":"2026-05-21T18:52:30.544538Z","steps":["trace[874416184] 'range keys from in-memory index tree' (duration: 200.715092ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:30.544582Z","caller":"traceutil/trace.go:171","msg":"trace[1964345400] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1267; }","duration":"443.352663ms","start":"2026-05-21T18:52:30.101207Z","end":"2026-05-21T18:52:30.54456Z","steps":["trace[1964345400] 'count revisions from in-memory index tree' (duration: 443.107225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:30.544639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:30.101121Z","time spent":"443.503237ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-21T18:52:31.186814Z","caller":"traceutil/trace.go:171","msg":"trace[447767663] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"248.975366ms","start":"2026-05-21T18:52:30.937812Z","end":"2026-05-21T18:52:31.186788Z","steps":["trace[447767663] 'read index received' (duration: 248.704739ms)","trace[447767663] 'applied index is now lower than readState.Index' (duration: 269.647µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:52:31.186911Z","caller":"traceutil/trace.go:171","msg":"trace[1694049821] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"638.398845ms","start":"2026-05-21T18:52:30.548474Z","end":"2026-05-21T18:52:31.186873Z","steps":["trace[1694049821] 'process raft request' (duration: 638.126658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:31.187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.161261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T18:52:31.187041Z","caller":"traceutil/trace.go:171","msg":"trace[1722677042] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1268; }","duration":"249.287724ms","start":"2026-05-21T18:52:30.93774Z","end":"2026-05-21T18:52:31.187027Z","steps":["trace[1722677042] 'agreement among raft nodes before linearized reading' (duration: 249.173261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:31.187092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:30.548447Z","time spent":"638.544528ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:52:31.187214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.272266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:52:31.187298Z","caller":"traceutil/trace.go:171","msg":"trace[1320476362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1268; }","duration":"163.364179ms","start":"2026-05-21T18:52:31.023909Z","end":"2026-05-21T18:52:31.187273Z","steps":["trace[1320476362] 'agreement among raft nodes before linearized reading' (duration: 163.231265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:31.615435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.712717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T18:52:31.615556Z","caller":"traceutil/trace.go:171","msg":"trace[1583701989] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"156.546621ms","start":"2026-05-21T18:52:31.458993Z","end":"2026-05-21T18:52:31.61554Z","steps":["trace[1583701989] 'read index received' (duration: 49.671µs)","trace[1583701989] 'applied index is now lower than readState.Index' (duration: 156.49558ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:52:31.615622Z","caller":"traceutil/trace.go:171","msg":"trace[799131965] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"363.320224ms","start":"2026-05-21T18:52:31.252293Z","end":"2026-05-21T18:52:31.615614Z","steps":["trace[799131965] 'process raft request' (duration: 159.33434ms)","trace[799131965] 'compare' (duration: 203.581244ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:52:31.61567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:31.252259Z","time spent":"363.385755ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:52:31.615779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.756846ms","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-21T18:52:31.615859Z","caller":"traceutil/trace.go:171","msg":"trace[1596274465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1269; }","duration":"156.899349ms","start":"2026-05-21T18:52:31.45894Z","end":"2026-05-21T18:52:31.615839Z","steps":["trace[1596274465] 'agreement among raft nodes before linearized reading' (duration: 156.676324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:32.116286Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199817288934177,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T18:52:32.295158Z","caller":"traceutil/trace.go:171","msg":"trace[2059974119] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"767.397803ms","start":"2026-05-21T18:52:31.527735Z","end":"2026-05-21T18:52:32.295133Z","steps":["trace[2059974119] 'process raft request' (duration: 767.232139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:32.295322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:31.527703Z","time spent":"767.530807ms","remote":"127.0.0.1:34484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T18:52:32.558496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.852261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T18:52:32.558743Z","caller":"traceutil/trace.go:171","msg":"trace[1470432273] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"207.999264ms","start":"2026-05-21T18:52:32.350682Z","end":"2026-05-21T18:52:32.558681Z","steps":["trace[1470432273] 'process raft request' (duration: 207.939362ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:32.558984Z","caller":"traceutil/trace.go:171","msg":"trace[478495449] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1326; }","duration":"943.375215ms","start":"2026-05-21T18:52:31.61558Z","end":"2026-05-21T18:52:32.558955Z","steps":["trace[478495449] 'read index received' (duration: 679.374196ms)","trace[478495449] 'applied index is now lower than readState.Index' (duration: 263.997999ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:52:32.559219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098196322s","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-21T18:52:32.559256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098329456s","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-21T18:52:32.559302Z","caller":"traceutil/trace.go:171","msg":"trace[12365509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"1.098337677s","start":"2026-05-21T18:52:31.460942Z","end":"2026-05-21T18:52:32.55928Z","steps":["trace[12365509] 'agreement among raft nodes before linearized reading' (duration: 1.098038489s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:32.559364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:31.460921Z","time spent":"1.098434599s","remote":"127.0.0.1:34616","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-21T18:52:32.559358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099899535s","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-21T18:52:32.559363Z","caller":"traceutil/trace.go:171","msg":"trace[952695404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1272; }","duration":"1.09847635s","start":"2026-05-21T18:52:31.460861Z","end":"2026-05-21T18:52:32.559337Z","steps":["trace[952695404] 'agreement among raft nodes before linearized reading' (duration: 1.098125192s)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:32.559415Z","caller":"traceutil/trace.go:171","msg":"trace[866020338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1272; }","duration":"1.100037299s","start":"2026-05-21T18:52:31.459365Z","end":"2026-05-21T18:52:32.559403Z","steps":["trace[866020338] 'agreement among raft nodes before linearized reading' (duration: 1.099784033s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:32.559436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:31.460849Z","time spent":"1.098573332s","remote":"127.0.0.1:34616","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-21T18:52:32.559467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:31.459336Z","time spent":"1.100118831s","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T18:52:32.559771Z","caller":"traceutil/trace.go:171","msg":"trace[1424529056] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"938.503805ms","start":"2026-05-21T18:52:31.621183Z","end":"2026-05-21T18:52:32.559687Z","steps":["trace[1424529056] 'process raft request' (duration: 718.341761ms)","trace[1424529056] 'compare' (duration: 218.686777ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:52:32.560807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:31.621155Z","time spent":"938.700679ms","remote":"127.0.0.1:34616","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-21T18:52:32.608417Z","caller":"traceutil/trace.go:171","msg":"trace[1662009067] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"188.010828ms","start":"2026-05-21T18:52:32.420363Z","end":"2026-05-21T18:52:32.608374Z","steps":["trace[1662009067] 'process raft request' (duration: 187.830414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:32.608577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.656648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-q8w5x\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-05-21T18:52:32.608618Z","caller":"traceutil/trace.go:171","msg":"trace[649757994] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-q8w5x; range_end:; response_count:1; response_revision:1273; }","duration":"136.74002ms","start":"2026-05-21T18:52:32.471862Z","end":"2026-05-21T18:52:32.608602Z","steps":["trace[649757994] 'agreement among raft nodes before linearized reading' (duration: 136.614717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:32.6089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.378695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:52:32.608953Z","caller":"traceutil/trace.go:171","msg":"trace[482160875] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1273; }","duration":"162.506578ms","start":"2026-05-21T18:52:32.446432Z","end":"2026-05-21T18:52:32.608938Z","steps":["trace[482160875] 'agreement among raft nodes before linearized reading' (duration: 162.400425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:32.608432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.44606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:52:32.609277Z","caller":"traceutil/trace.go:171","msg":"trace[1648090363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"585.199339ms","start":"2026-05-21T18:52:32.023911Z","end":"2026-05-21T18:52:32.60911Z","steps":["trace[1648090363] 'agreement among raft nodes before linearized reading' (duration: 584.436269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:32.609521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:32.023885Z","time spent":"585.607829ms","remote":"127.0.0.1:34394","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-21T18:52:38.52519Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199817288934245,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T18:52:38.604122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412545446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:52:38.604235Z","caller":"traceutil/trace.go:171","msg":"trace[1952826006] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1308; }","duration":"1.41270785s","start":"2026-05-21T18:52:37.191496Z","end":"2026-05-21T18:52:38.604203Z","steps":["trace[1952826006] 'count revisions from in-memory index tree' (duration: 1.412426933s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:38.604324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:37.191475Z","time spent":"1.412815152s","remote":"127.0.0.1:54762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T18:52:38.604753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22869217s","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-21T18:52:38.604796Z","caller":"traceutil/trace.go:171","msg":"trace[2038451954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1308; }","duration":"1.228793782s","start":"2026-05-21T18:52:37.375989Z","end":"2026-05-21T18:52:38.604783Z","steps":["trace[2038451954] 'range keys from in-memory index tree' (duration: 1.228233648s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:38.604833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:37.375961Z","time spent":"1.228857104s","remote":"127.0.0.1:34616","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-21T18:52:38.931838Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.090631646s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T18:52:38.932271Z","caller":"traceutil/trace.go:171","msg":"trace[1501203164] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1366; }","duration":"907.716612ms","start":"2026-05-21T18:52:38.024533Z","end":"2026-05-21T18:52:38.932249Z","steps":["trace[1501203164] 'read index received' (duration: 907.681931ms)","trace[1501203164] 'applied index is now lower than readState.Index' (duration: 33.881µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:52:38.932393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.848606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:52:38.932418Z","caller":"traceutil/trace.go:171","msg":"trace[2009958475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"907.941348ms","start":"2026-05-21T18:52:38.024468Z","end":"2026-05-21T18:52:38.93241Z","steps":["trace[2009958475] 'agreement among raft nodes before linearized reading' (duration: 907.881737ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:38.932406Z","caller":"traceutil/trace.go:171","msg":"trace[582862822] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"1.293769898s","start":"2026-05-21T18:52:37.638601Z","end":"2026-05-21T18:52:38.932371Z","steps":["trace[582862822] 'process raft request' (duration: 1.293456871s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:38.933181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:37.638565Z","time spent":"1.294402524s","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:52:38.932499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:38.024444Z","time spent":"907.992179ms","remote":"127.0.0.1:34394","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-21T18:52:38.93445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.186805ms","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-21T18:52:38.934458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.918561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:125272"} {"level":"info","ts":"2026-05-21T18:52:38.934495Z","caller":"traceutil/trace.go:171","msg":"trace[697103464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1310; }","duration":"246.309449ms","start":"2026-05-21T18:52:38.688174Z","end":"2026-05-21T18:52:38.934484Z","steps":["trace[697103464] 'agreement among raft nodes before linearized reading' (duration: 246.134085ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:38.93452Z","caller":"traceutil/trace.go:171","msg":"trace[338373575] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1310; }","duration":"786.025903ms","start":"2026-05-21T18:52:38.148477Z","end":"2026-05-21T18:52:38.934503Z","steps":["trace[338373575] 'agreement among raft nodes before linearized reading' (duration: 785.806568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:38.934565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:38.148455Z","time spent":"786.097846ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":125294,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-05-21T18:52:38.93468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.90047ms","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-21T18:52:38.934699Z","caller":"traceutil/trace.go:171","msg":"trace[744940760] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"245.941781ms","start":"2026-05-21T18:52:38.688752Z","end":"2026-05-21T18:52:38.934693Z","steps":["trace[744940760] 'agreement among raft nodes before linearized reading' (duration: 245.88682ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:38.934969Z","caller":"traceutil/trace.go:171","msg":"trace[348323448] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"324.979005ms","start":"2026-05-21T18:52:38.609981Z","end":"2026-05-21T18:52:38.93496Z","steps":["trace[348323448] 'process raft request' (duration: 324.186634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:38.935055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:38.609957Z","time spent":"325.041496ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T18:52:38.935093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.788047ms","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-21T18:52:38.935148Z","caller":"traceutil/trace.go:171","msg":"trace[70300119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1310; }","duration":"279.894761ms","start":"2026-05-21T18:52:38.655239Z","end":"2026-05-21T18:52:38.935134Z","steps":["trace[70300119] 'agreement among raft nodes before linearized reading' (duration: 279.702286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:38.935205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.67528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-21T18:52:38.935243Z","caller":"traceutil/trace.go:171","msg":"trace[1256640608] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1310; }","duration":"786.702531ms","start":"2026-05-21T18:52:38.148523Z","end":"2026-05-21T18:52:38.935225Z","steps":["trace[1256640608] 'agreement among raft nodes before linearized reading' (duration: 786.65622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:52:38.935278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:52:38.148476Z","time spent":"786.791523ms","remote":"127.0.0.1:34474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-21T18:52:38.935728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.746091ms","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-21T18:52:38.935914Z","caller":"traceutil/trace.go:171","msg":"trace[1338830605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1310; }","duration":"246.967095ms","start":"2026-05-21T18:52:38.688853Z","end":"2026-05-21T18:52:38.93582Z","steps":["trace[1338830605] 'agreement among raft nodes before linearized reading' (duration: 245.435597ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:51.154327Z","caller":"traceutil/trace.go:171","msg":"trace[1205464577] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"104.482612ms","start":"2026-05-21T18:52:51.049815Z","end":"2026-05-21T18:52:51.154297Z","steps":["trace[1205464577] 'process raft request' (duration: 104.386219ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:51.154355Z","caller":"traceutil/trace.go:171","msg":"trace[1179888765] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"104.549973ms","start":"2026-05-21T18:52:51.049766Z","end":"2026-05-21T18:52:51.154316Z","steps":["trace[1179888765] 'process raft request' (duration: 86.881846ms)","trace[1179888765] 'compare' (duration: 17.40969ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:52:51.154764Z","caller":"traceutil/trace.go:171","msg":"trace[2012759157] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"103.140908ms","start":"2026-05-21T18:52:51.051572Z","end":"2026-05-21T18:52:51.154712Z","steps":["trace[2012759157] 'process raft request' (duration: 103.021615ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:52:51.154795Z","caller":"traceutil/trace.go:171","msg":"trace[232775966] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"103.514627ms","start":"2026-05-21T18:52:51.051246Z","end":"2026-05-21T18:52:51.154761Z","steps":["trace[232775966] 'process raft request' (duration: 103.007465ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:53:13.184811Z","caller":"traceutil/trace.go:171","msg":"trace[1874275029] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"113.74958ms","start":"2026-05-21T18:53:13.071013Z","end":"2026-05-21T18:53:13.184763Z","steps":["trace[1874275029] 'process raft request' (duration: 67.323303ms)","trace[1874275029] 'compare' (duration: 46.289123ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:53:13.559246Z","caller":"traceutil/trace.go:171","msg":"trace[102756603] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"109.924245ms","start":"2026-05-21T18:53:13.449288Z","end":"2026-05-21T18:53:13.559212Z","steps":["trace[102756603] 'process raft request' (duration: 109.767921ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:55:29.609043Z","caller":"traceutil/trace.go:171","msg":"trace[1774494534] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"213.995868ms","start":"2026-05-21T18:55:29.395008Z","end":"2026-05-21T18:55:29.609004Z","steps":["trace[1774494534] 'process raft request' (duration: 213.65952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:55:30.017427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.060635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T18:55:30.018294Z","caller":"traceutil/trace.go:171","msg":"trace[2017038717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2116; }","duration":"107.222189ms","start":"2026-05-21T18:55:29.910291Z","end":"2026-05-21T18:55:30.017513Z","steps":["trace[2017038717] 'range keys from in-memory index tree' (duration: 106.756208ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:55:30.247772Z","caller":"traceutil/trace.go:171","msg":"trace[1879853771] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"225.698875ms","start":"2026-05-21T18:55:30.022047Z","end":"2026-05-21T18:55:30.247746Z","steps":["trace[1879853771] 'process raft request' (duration: 176.006536ms)","trace[1879853771] 'compare' (duration: 49.532975ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:55:30.247923Z","caller":"traceutil/trace.go:171","msg":"trace[1765937736] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2209; }","duration":"224.035483ms","start":"2026-05-21T18:55:30.02386Z","end":"2026-05-21T18:55:30.247895Z","steps":["trace[1765937736] 'read index received' (duration: 13.052209ms)","trace[1765937736] 'applied index is now lower than readState.Index' (duration: 210.981974ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T18:55:30.248033Z","caller":"traceutil/trace.go:171","msg":"trace[969550998] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"225.482619ms","start":"2026-05-21T18:55:30.022539Z","end":"2026-05-21T18:55:30.248022Z","steps":["trace[969550998] 'process raft request' (duration: 225.165192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:55:30.248188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.30767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T18:55:30.248227Z","caller":"traceutil/trace.go:171","msg":"trace[705556682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2119; }","duration":"224.375661ms","start":"2026-05-21T18:55:30.023841Z","end":"2026-05-21T18:55:30.248217Z","steps":["trace[705556682] 'agreement among raft nodes before linearized reading' (duration: 224.300639ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:55:30.269417Z","caller":"traceutil/trace.go:171","msg":"trace[684509148] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"154.625861ms","start":"2026-05-21T18:55:30.114746Z","end":"2026-05-21T18:55:30.269372Z","steps":["trace[684509148] 'process raft request' (duration: 154.405956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:55:30.269493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.573593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-21T18:55:30.269553Z","caller":"traceutil/trace.go:171","msg":"trace[472233591] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2120; }","duration":"230.672565ms","start":"2026-05-21T18:55:30.038865Z","end":"2026-05-21T18:55:30.269538Z","steps":["trace[472233591] 'agreement among raft nodes before linearized reading' (duration: 230.545802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:55:30.269659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.454384ms","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-21T18:55:30.26972Z","caller":"traceutil/trace.go:171","msg":"trace[238842729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2120; }","duration":"120.585848ms","start":"2026-05-21T18:55:30.149118Z","end":"2026-05-21T18:55:30.269704Z","steps":["trace[238842729] 'agreement among raft nodes before linearized reading' (duration: 120.356472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:55:30.270123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.646609ms","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-21T18:55:30.270176Z","caller":"traceutil/trace.go:171","msg":"trace[630836265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2120; }","duration":"120.760121ms","start":"2026-05-21T18:55:30.149404Z","end":"2026-05-21T18:55:30.270164Z","steps":["trace[630836265] 'agreement among raft nodes before linearized reading' (duration: 119.90247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:55:30.270221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.000813ms","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-21T18:55:30.270367Z","caller":"traceutil/trace.go:171","msg":"trace[1690727198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2120; }","duration":"120.167687ms","start":"2026-05-21T18:55:30.150155Z","end":"2026-05-21T18:55:30.270322Z","steps":["trace[1690727198] 'agreement among raft nodes before linearized reading' (duration: 119.813749ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:56:30.683033Z","caller":"traceutil/trace.go:171","msg":"trace[1084866257] transaction","detail":"{read_only:false; response_revision:2422; number_of_response:1; }","duration":"140.809048ms","start":"2026-05-21T18:56:30.542204Z","end":"2026-05-21T18:56:30.683013Z","steps":["trace[1084866257] 'process raft request' (duration: 140.647494ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:56:30.683375Z","caller":"traceutil/trace.go:171","msg":"trace[1469607797] linearizableReadLoop","detail":"{readStateIndex:2528; appliedIndex:2527; }","duration":"104.203947ms","start":"2026-05-21T18:56:30.578765Z","end":"2026-05-21T18:56:30.682969Z","steps":["trace[1469607797] 'read index received' (duration: 104.035133ms)","trace[1469607797] 'applied index is now lower than readState.Index' (duration: 167.914µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:56:30.683857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.052617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T18:56:30.684076Z","caller":"traceutil/trace.go:171","msg":"trace[1147347531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2422; }","duration":"105.345004ms","start":"2026-05-21T18:56:30.578706Z","end":"2026-05-21T18:56:30.684051Z","steps":["trace[1147347531] 'agreement among raft nodes before linearized reading' (duration: 104.411242ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:56:31.1375Z","caller":"traceutil/trace.go:171","msg":"trace[692089877] transaction","detail":"{read_only:false; response_revision:2425; number_of_response:1; }","duration":"447.651859ms","start":"2026-05-21T18:56:30.689811Z","end":"2026-05-21T18:56:31.137463Z","steps":["trace[692089877] 'process raft request' (duration: 447.558817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:56:31.137695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:56:30.689803Z","time spent":"447.778982ms","remote":"127.0.0.1:34616","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-21T18:56:31.137824Z","caller":"traceutil/trace.go:171","msg":"trace[801960051] transaction","detail":"{read_only:false; response_revision:2426; number_of_response:1; }","duration":"428.383469ms","start":"2026-05-21T18:56:30.709413Z","end":"2026-05-21T18:56:31.137797Z","steps":["trace[801960051] 'process raft request' (duration: 428.019431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T18:56:31.137833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.854378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T18:56:31.137864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:56:30.70938Z","time spent":"428.461731ms","remote":"127.0.0.1:34616","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-21T18:56:31.137882Z","caller":"traceutil/trace.go:171","msg":"trace[341421385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2426; }","duration":"112.906089ms","start":"2026-05-21T18:56:31.024962Z","end":"2026-05-21T18:56:31.137868Z","steps":["trace[341421385] 'agreement among raft nodes before linearized reading' (duration: 112.797166ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T18:56:31.138036Z","caller":"traceutil/trace.go:171","msg":"trace[1565054535] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"449.030655ms","start":"2026-05-21T18:56:30.688985Z","end":"2026-05-21T18:56:31.138016Z","steps":["trace[1565054535] 'process raft request' (duration: 436.95932ms)","trace[1565054535] 'compare' (duration: 10.472015ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:56:31.138114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T18:56:30.688978Z","time spent":"449.096266ms","remote":"127.0.0.1:34616","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-21T18:56:31.137721Z","caller":"traceutil/trace.go:171","msg":"trace[1797236983] linearizableReadLoop","detail":"{readStateIndex:2532; appliedIndex:2529; }","duration":"112.674164ms","start":"2026-05-21T18:56:31.025016Z","end":"2026-05-21T18:56:31.13769Z","steps":["trace[1797236983] 'read index received' (duration: 100.845176ms)","trace[1797236983] 'applied index is now lower than readState.Index' (duration: 11.828028ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T18:56:31.138264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.465411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T18:56:31.138321Z","caller":"traceutil/trace.go:171","msg":"trace[266196708] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2426; }","duration":"108.524633ms","start":"2026-05-21T18:56:31.02979Z","end":"2026-05-21T18:56:31.138315Z","steps":["trace[266196708] 'agreement among raft nodes before linearized reading' (duration: 108.439861ms)"],"step_count":1}