{"level":"warn","ts":"2026-07-22T22:09:32.493149Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T22:09:32.493225Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-07-22T22:09:32.493281Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T22:09:32.493298Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-22T22:09:32.493317Z","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-07-22T22:09:32.493945Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-07-22T22:09:32.494023Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-07-22T22:09:32.496683Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.441967ms"} {"level":"info","ts":"2026-07-22T22:09:32.500997Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-07-22T22:09:32.501052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-22T22:09:32.501069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-07-22T22:09:32.501078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-22T22:09:32.501084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-07-22T22:09:32.501111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-07-22T22:09:32.510978Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-22T22:09:32.512473Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-22T22:09:32.513608Z","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-07-22T22:09:32.515753Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-22T22:09:32.51659Z","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-07-22T22:09:32.516566Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-22T22:09:32.516661Z","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-07-22T22:09:32.516674Z","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-07-22T22:09:32.519891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-07-22T22:09:32.52028Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-22T22:09:32.521492Z","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-07-22T22:09:32.521817Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-22T22:09:32.521876Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-22T22:09:32.521953Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-22T22:09:32.522044Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-22T22:09:32.701875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-22T22:09:32.701943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-22T22:09:32.701987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-07-22T22:09:32.702007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-07-22T22:09:32.702019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-22T22:09:32.702041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-07-22T22:09:32.702138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-22T22:09:32.703129Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:09:32.704003Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-07-22T22:09:32.703997Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:09:32.704325Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-22T22:09:32.704413Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-22T22:09:32.704185Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:09:32.704594Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:09:32.704764Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:09:32.704811Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:09:32.707614Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-07-22T22:09:32.709516Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-22T22:09:39.307648Z","caller":"traceutil/trace.go:171","msg":"trace[45616995] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"397.788375ms","start":"2026-07-22T22:09:38.909828Z","end":"2026-07-22T22:09:39.307617Z","steps":["trace[45616995] 'process raft request' (duration: 314.74923ms)","trace[45616995] 'compare' (duration: 82.762266ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:09:39.307912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.490971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-07-22T22:09:39.308028Z","caller":"traceutil/trace.go:171","msg":"trace[1792380562] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:307; }","duration":"165.631305ms","start":"2026-07-22T22:09:39.142368Z","end":"2026-07-22T22:09:39.307999Z","steps":["trace[1792380562] 'agreement among raft nodes before linearized reading' (duration: 165.381737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:39.308213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:38.909808Z","time spent":"397.928009ms","remote":"127.0.0.1:59902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:09:39.308497Z","caller":"traceutil/trace.go:171","msg":"trace[305545689] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"165.175421ms","start":"2026-07-22T22:09:39.142396Z","end":"2026-07-22T22:09:39.307571Z","steps":["trace[305545689] 'read index received' (duration: 82.126976ms)","trace[305545689] 'applied index is now lower than readState.Index' (duration: 83.047295ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:09:39.308614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.075891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:09:39.308648Z","caller":"traceutil/trace.go:171","msg":"trace[132434704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:307; }","duration":"161.126443ms","start":"2026-07-22T22:09:39.147508Z","end":"2026-07-22T22:09:39.308634Z","steps":["trace[132434704] 'agreement among raft nodes before linearized reading' (duration: 161.066671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:40.128525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.135415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:09:40.12862Z","caller":"traceutil/trace.go:171","msg":"trace[1317624542] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"814.438163ms","start":"2026-07-22T22:09:39.314168Z","end":"2026-07-22T22:09:40.128606Z","steps":["trace[1317624542] 'read index received' (duration: 287.656697ms)","trace[1317624542] 'applied index is now lower than readState.Index' (duration: 526.780276ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:09:40.128713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.540356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:09:40.128772Z","caller":"traceutil/trace.go:171","msg":"trace[180055345] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:308; }","duration":"814.66105ms","start":"2026-07-22T22:09:39.314101Z","end":"2026-07-22T22:09:40.128762Z","steps":["trace[180055345] 'agreement among raft nodes before linearized reading' (duration: 814.558927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:40.128805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:39.314092Z","time spent":"814.705341ms","remote":"127.0.0.1:59902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-07-22T22:09:40.129014Z","caller":"traceutil/trace.go:171","msg":"trace[311214275] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"815.569719ms","start":"2026-07-22T22:09:39.313427Z","end":"2026-07-22T22:09:40.128997Z","steps":["trace[311214275] 'process raft request' (duration: 288.38825ms)","trace[311214275] 'compare' (duration: 525.712232ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:09:40.12909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:39.313406Z","time spent":"815.650321ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:09:40.632936Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394882802669479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:09:40.934961Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.28628263s","expected-duration":"1s"} {"level":"info","ts":"2026-07-22T22:09:41.014267Z","caller":"traceutil/trace.go:171","msg":"trace[1036302952] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"1.689403061s","start":"2026-07-22T22:09:39.324827Z","end":"2026-07-22T22:09:41.01423Z","steps":["trace[1036302952] 'process raft request' (duration: 1.6105773s)","trace[1036302952] 'compare' (duration: 78.623735ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:09:41.014758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:39.324815Z","time spent":"1.689557767s","remote":"127.0.0.1:59888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:09:41.100435Z","caller":"traceutil/trace.go:171","msg":"trace[1675338538] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:316; }","duration":"968.188454ms","start":"2026-07-22T22:09:40.132215Z","end":"2026-07-22T22:09:41.100403Z","steps":["trace[1675338538] 'read index received' (duration: 803.128547ms)","trace[1675338538] 'applied index is now lower than readState.Index' (duration: 165.058967ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:09:41.10047Z","caller":"traceutil/trace.go:171","msg":"trace[446873828] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"967.141011ms","start":"2026-07-22T22:09:40.133294Z","end":"2026-07-22T22:09:41.100435Z","steps":["trace[446873828] 'process raft request' (duration: 966.994516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:41.100854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.613957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-22T22:09:41.100901Z","caller":"traceutil/trace.go:171","msg":"trace[274087711] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:310; }","duration":"968.70176ms","start":"2026-07-22T22:09:40.132184Z","end":"2026-07-22T22:09:41.100886Z","steps":["trace[274087711] 'agreement among raft nodes before linearized reading' (duration: 968.349829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:41.100949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:40.132168Z","time spent":"968.772343ms","remote":"127.0.0.1:59848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-22T22:09:41.100987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.002871ms","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-07-22T22:09:41.10106Z","caller":"traceutil/trace.go:171","msg":"trace[1149081253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:310; }","duration":"243.116325ms","start":"2026-07-22T22:09:40.857926Z","end":"2026-07-22T22:09:41.101042Z","steps":["trace[1149081253] 'agreement among raft nodes before linearized reading' (duration: 242.930279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:41.101133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.862745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-22T22:09:41.101166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.952076ms","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-07-22T22:09:41.101286Z","caller":"traceutil/trace.go:171","msg":"trace[341434703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:310; }","duration":"247.159502ms","start":"2026-07-22T22:09:40.854102Z","end":"2026-07-22T22:09:41.101261Z","steps":["trace[341434703] 'agreement among raft nodes before linearized reading' (duration: 246.821531ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:09:41.101233Z","caller":"traceutil/trace.go:171","msg":"trace[85835509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:310; }","duration":"969.005299ms","start":"2026-07-22T22:09:40.132212Z","end":"2026-07-22T22:09:41.101217Z","steps":["trace[85835509] 'agreement among raft nodes before linearized reading' (duration: 968.635608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:41.101478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:40.133276Z","time spent":"967.270885ms","remote":"127.0.0.1:59792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:09:41.101487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:40.132194Z","time spent":"969.270518ms","remote":"127.0.0.1:59968","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-07-22T22:09:41.10187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.161444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-22T22:09:41.101989Z","caller":"traceutil/trace.go:171","msg":"trace[1659598548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:310; }","duration":"888.290758ms","start":"2026-07-22T22:09:40.213671Z","end":"2026-07-22T22:09:41.101962Z","steps":["trace[1659598548] 'agreement among raft nodes before linearized reading' (duration: 888.113192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:41.102044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:40.213597Z","time spent":"888.429093ms","remote":"127.0.0.1:59968","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-07-22T22:09:41.622155Z","caller":"traceutil/trace.go:171","msg":"trace[1633854317] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"515.547632ms","start":"2026-07-22T22:09:41.106582Z","end":"2026-07-22T22:09:41.622129Z","steps":["trace[1633854317] 'process raft request' (duration: 475.252783ms)","trace[1633854317] 'compare' (duration: 39.888286ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:09:41.622188Z","caller":"traceutil/trace.go:171","msg":"trace[1319719425] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"515.209761ms","start":"2026-07-22T22:09:41.106963Z","end":"2026-07-22T22:09:41.622172Z","steps":["trace[1319719425] 'process raft request' (duration: 514.906852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:41.622282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:41.106548Z","time spent":"515.675826ms","remote":"127.0.0.1:59902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:09:41.622294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:41.106955Z","time spent":"515.274943ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:09:41.622278Z","caller":"traceutil/trace.go:171","msg":"trace[2138482957] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"512.678771ms","start":"2026-07-22T22:09:41.109558Z","end":"2026-07-22T22:09:41.622237Z","steps":["trace[2138482957] 'process raft request' (duration: 512.566508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:41.622481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:41.109546Z","time spent":"512.831166ms","remote":"127.0.0.1:59888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:09:41.62253Z","caller":"traceutil/trace.go:171","msg":"trace[1652602842] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"515.322124ms","start":"2026-07-22T22:09:41.107198Z","end":"2026-07-22T22:09:41.62252Z","steps":["trace[1652602842] 'process raft request' (duration: 514.752516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:41.6226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:41.10717Z","time spent":"515.395646ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:09:41.622728Z","caller":"traceutil/trace.go:171","msg":"trace[2068733363] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"514.898311ms","start":"2026-07-22T22:09:41.107821Z","end":"2026-07-22T22:09:41.622719Z","steps":["trace[2068733363] 'process raft request' (duration: 514.256391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:41.622788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:41.107799Z","time spent":"514.959913ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:09:41.622496Z","caller":"traceutil/trace.go:171","msg":"trace[724641498] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"515.436308ms","start":"2026-07-22T22:09:41.107048Z","end":"2026-07-22T22:09:41.622484Z","steps":["trace[724641498] 'process raft request' (duration: 514.87311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:09:41.622919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:09:41.107042Z","time spent":"515.843931ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:09:42.652923Z","caller":"traceutil/trace.go:171","msg":"trace[1118281106] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"173.75744ms","start":"2026-07-22T22:09:42.479129Z","end":"2026-07-22T22:09:42.652886Z","steps":["trace[1118281106] 'process raft request' (duration: 173.003196ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:10.013112Z","caller":"traceutil/trace.go:171","msg":"trace[1727298387] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"250.211638ms","start":"2026-07-22T22:10:09.762875Z","end":"2026-07-22T22:10:10.013086Z","steps":["trace[1727298387] 'process raft request' (duration: 250.060953ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:10.013252Z","caller":"traceutil/trace.go:171","msg":"trace[249602804] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:544; }","duration":"249.364671ms","start":"2026-07-22T22:10:09.763793Z","end":"2026-07-22T22:10:10.013157Z","steps":["trace[249602804] 'read index received' (duration: 249.307179ms)","trace[249602804] 'applied index is now lower than readState.Index' (duration: 46.832µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:10:10.013756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.945839ms","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-07-22T22:10:10.013784Z","caller":"traceutil/trace.go:171","msg":"trace[921533524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"250.023812ms","start":"2026-07-22T22:10:09.763752Z","end":"2026-07-22T22:10:10.013776Z","steps":["trace[921533524] 'agreement among raft nodes before linearized reading' (duration: 249.870507ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:10.089833Z","caller":"traceutil/trace.go:171","msg":"trace[21563084] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"325.478268ms","start":"2026-07-22T22:10:09.764329Z","end":"2026-07-22T22:10:10.089807Z","steps":["trace[21563084] 'process raft request' (duration: 325.205139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:10.089974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:09.764312Z","time spent":"325.587762ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:10:10.090128Z","caller":"traceutil/trace.go:171","msg":"trace[82732039] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"232.984566ms","start":"2026-07-22T22:10:09.857114Z","end":"2026-07-22T22:10:10.090098Z","steps":["trace[82732039] 'process raft request' (duration: 232.712717ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:10.090188Z","caller":"traceutil/trace.go:171","msg":"trace[1635671238] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"224.577651ms","start":"2026-07-22T22:10:09.865546Z","end":"2026-07-22T22:10:10.090124Z","steps":["trace[1635671238] 'process raft request' (duration: 224.465267ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:10.090264Z","caller":"traceutil/trace.go:171","msg":"trace[914267987] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"248.941398ms","start":"2026-07-22T22:10:09.841313Z","end":"2026-07-22T22:10:10.090255Z","steps":["trace[914267987] 'process raft request' (duration: 248.39524ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:10.090287Z","caller":"traceutil/trace.go:171","msg":"trace[970735045] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"230.955042ms","start":"2026-07-22T22:10:09.859306Z","end":"2026-07-22T22:10:10.090261Z","steps":["trace[970735045] 'process raft request' (duration: 230.56432ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:10.090351Z","caller":"traceutil/trace.go:171","msg":"trace[532617330] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"241.259156ms","start":"2026-07-22T22:10:09.849078Z","end":"2026-07-22T22:10:10.090337Z","steps":["trace[532617330] 'process raft request' (duration: 240.678418ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:10.090232Z","caller":"traceutil/trace.go:171","msg":"trace[1567594445] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"224.853719ms","start":"2026-07-22T22:10:09.86536Z","end":"2026-07-22T22:10:10.090213Z","steps":["trace[1567594445] 'process raft request' (duration: 224.56305ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:10.090345Z","caller":"traceutil/trace.go:171","msg":"trace[1337113813] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"224.842779ms","start":"2026-07-22T22:10:09.865447Z","end":"2026-07-22T22:10:10.090289Z","steps":["trace[1337113813] 'process raft request' (duration: 224.511118ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:10.090452Z","caller":"traceutil/trace.go:171","msg":"trace[184028899] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"241.154653ms","start":"2026-07-22T22:10:09.849263Z","end":"2026-07-22T22:10:10.090418Z","steps":["trace[184028899] 'process raft request' (duration: 240.526863ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:10.090693Z","caller":"traceutil/trace.go:171","msg":"trace[768754676] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"180.554715ms","start":"2026-07-22T22:10:09.910128Z","end":"2026-07-22T22:10:10.090683Z","steps":["trace[768754676] 'process raft request' (duration: 180.451412ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:10.090741Z","caller":"traceutil/trace.go:171","msg":"trace[1714405431] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"198.054125ms","start":"2026-07-22T22:10:09.892671Z","end":"2026-07-22T22:10:10.090725Z","steps":["trace[1714405431] 'process raft request' (duration: 197.406535ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:10.09069Z","caller":"traceutil/trace.go:171","msg":"trace[321248250] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"196.801976ms","start":"2026-07-22T22:10:09.893871Z","end":"2026-07-22T22:10:10.090673Z","steps":["trace[321248250] 'process raft request' (duration: 196.507217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:10.090741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.379064ms","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-07-22T22:10:10.090831Z","caller":"traceutil/trace.go:171","msg":"trace[340226873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"325.490678ms","start":"2026-07-22T22:10:09.765316Z","end":"2026-07-22T22:10:10.090807Z","steps":["trace[340226873] 'agreement among raft nodes before linearized reading' (duration: 325.339463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:10.090883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:09.765305Z","time spent":"325.56265ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-22T22:10:10.927601Z","caller":"traceutil/trace.go:171","msg":"trace[53125980] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"153.42379ms","start":"2026-07-22T22:10:10.774156Z","end":"2026-07-22T22:10:10.92758Z","steps":["trace[53125980] 'process raft request' (duration: 153.011887ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:11.049945Z","caller":"traceutil/trace.go:171","msg":"trace[1733224008] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"272.390746ms","start":"2026-07-22T22:10:10.777531Z","end":"2026-07-22T22:10:11.049922Z","steps":["trace[1733224008] 'process raft request' (duration: 225.717187ms)","trace[1733224008] 'compare' (duration: 46.402991ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:10:16.22571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.028409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-22T22:10:16.226352Z","caller":"traceutil/trace.go:171","msg":"trace[1569676319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"107.170734ms","start":"2026-07-22T22:10:16.118633Z","end":"2026-07-22T22:10:16.225803Z","steps":["trace[1569676319] 'range keys from in-memory index tree' (duration: 106.913316ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:16.371319Z","caller":"traceutil/trace.go:171","msg":"trace[978549921] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"147.922718ms","start":"2026-07-22T22:10:16.223371Z","end":"2026-07-22T22:10:16.371294Z","steps":["trace[978549921] 'process raft request' (duration: 146.39726ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:16.37364Z","caller":"traceutil/trace.go:171","msg":"trace[1849773267] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"143.5406ms","start":"2026-07-22T22:10:16.230073Z","end":"2026-07-22T22:10:16.373614Z","steps":["trace[1849773267] 'process raft request' (duration: 143.463218ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:16.373903Z","caller":"traceutil/trace.go:171","msg":"trace[1416636330] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"143.684274ms","start":"2026-07-22T22:10:16.230199Z","end":"2026-07-22T22:10:16.373883Z","steps":["trace[1416636330] 'process raft request' (duration: 143.377465ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:16.373671Z","caller":"traceutil/trace.go:171","msg":"trace[457750714] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"143.766557ms","start":"2026-07-22T22:10:16.229872Z","end":"2026-07-22T22:10:16.373638Z","steps":["trace[457750714] 'process raft request' (duration: 143.525989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:16.591126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.608936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:10:16.591255Z","caller":"traceutil/trace.go:171","msg":"trace[1473612071] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"163.410065ms","start":"2026-07-22T22:10:16.427813Z","end":"2026-07-22T22:10:16.591224Z","steps":["trace[1473612071] 'process raft request' (duration: 16.625543ms)","trace[1473612071] 'compare' (duration: 146.43155ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:10:17.642774Z","caller":"traceutil/trace.go:171","msg":"trace[1764998713] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"494.174778ms","start":"2026-07-22T22:10:17.148573Z","end":"2026-07-22T22:10:17.642747Z","steps":["trace[1764998713] 'read index received' (duration: 493.372132ms)","trace[1764998713] 'applied index is now lower than readState.Index' (duration: 801.816µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:10:17.642935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.340923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:10:17.64297Z","caller":"traceutil/trace.go:171","msg":"trace[1078479339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"494.444836ms","start":"2026-07-22T22:10:17.148511Z","end":"2026-07-22T22:10:17.642956Z","steps":["trace[1078479339] 'agreement among raft nodes before linearized reading' (duration: 494.339413ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:17.642944Z","caller":"traceutil/trace.go:171","msg":"trace[633738664] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"618.968157ms","start":"2026-07-22T22:10:17.023909Z","end":"2026-07-22T22:10:17.642877Z","steps":["trace[633738664] 'process raft request' (duration: 618.288835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:17.643021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:17.148489Z","time spent":"494.525259ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:10:17.643357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:17.02388Z","time spent":"619.266616ms","remote":"127.0.0.1:59816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:10:18.919535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170477872s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:10:18.91962Z","caller":"traceutil/trace.go:171","msg":"trace[748588350] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"873.324037ms","start":"2026-07-22T22:10:18.046279Z","end":"2026-07-22T22:10:18.919603Z","steps":["trace[748588350] 'read index received' (duration: 38.002µs)","trace[748588350] 'applied index is now lower than readState.Index' (duration: 873.284605ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:10:18.919762Z","caller":"traceutil/trace.go:171","msg":"trace[1454352494] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.201512849s","start":"2026-07-22T22:10:17.718237Z","end":"2026-07-22T22:10:18.91975Z","steps":["trace[1454352494] 'process raft request' (duration: 30.757248ms)","trace[1454352494] 'compare' (duration: 1.170099991s)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:10:18.91983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:17.718216Z","time spent":"1.201586422s","remote":"127.0.0.1:59934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:10:18.920091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.813752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-22T22:10:18.920134Z","caller":"traceutil/trace.go:171","msg":"trace[1362322442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"873.894484ms","start":"2026-07-22T22:10:18.04623Z","end":"2026-07-22T22:10:18.920125Z","steps":["trace[1362322442] 'agreement among raft nodes before linearized reading' (duration: 873.76444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:18.920163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:18.046211Z","time spent":"873.945506ms","remote":"127.0.0.1:59968","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-07-22T22:10:18.958569Z","caller":"traceutil/trace.go:171","msg":"trace[2119077641] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"132.852452ms","start":"2026-07-22T22:10:18.825683Z","end":"2026-07-22T22:10:18.958535Z","steps":["trace[2119077641] 'process raft request' (duration: 132.724188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:18.958864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.654512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-22T22:10:18.958923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.037872ms","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-07-22T22:10:18.95895Z","caller":"traceutil/trace.go:171","msg":"trace[103492234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"580.871939ms","start":"2026-07-22T22:10:18.378057Z","end":"2026-07-22T22:10:18.958929Z","steps":["trace[103492234] 'agreement among raft nodes before linearized reading' (duration: 580.562399ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:18.959012Z","caller":"traceutil/trace.go:171","msg":"trace[801106568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"580.194998ms","start":"2026-07-22T22:10:18.378795Z","end":"2026-07-22T22:10:18.95899Z","steps":["trace[801106568] 'agreement among raft nodes before linearized reading' (duration: 579.800065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:18.959019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:18.378042Z","time spent":"580.960981ms","remote":"127.0.0.1:59968","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-07-22T22:10:18.959062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:18.378785Z","time spent":"580.26766ms","remote":"127.0.0.1:59968","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-07-22T22:10:18.959071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.371263ms","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-07-22T22:10:18.959138Z","caller":"traceutil/trace.go:171","msg":"trace[661811987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"580.457656ms","start":"2026-07-22T22:10:18.378662Z","end":"2026-07-22T22:10:18.95912Z","steps":["trace[661811987] 'agreement among raft nodes before linearized reading' (duration: 580.230869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:18.959236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:18.378652Z","time spent":"580.569079ms","remote":"127.0.0.1:59968","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-07-22T22:10:18.958909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.23486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:10:18.9595Z","caller":"traceutil/trace.go:171","msg":"trace[811271467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"810.834859ms","start":"2026-07-22T22:10:18.148615Z","end":"2026-07-22T22:10:18.95945Z","steps":["trace[811271467] 'agreement among raft nodes before linearized reading' (duration: 810.2349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:18.959635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:18.148596Z","time spent":"811.023796ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-22T22:10:20.672597Z","caller":"traceutil/trace.go:171","msg":"trace[242917045] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"254.313347ms","start":"2026-07-22T22:10:20.418258Z","end":"2026-07-22T22:10:20.672571Z","steps":["trace[242917045] 'process raft request' (duration: 254.181383ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:34.174267Z","caller":"traceutil/trace.go:171","msg":"trace[266946698] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"756.047935ms","start":"2026-07-22T22:10:33.418199Z","end":"2026-07-22T22:10:34.174247Z","steps":["trace[266946698] 'process raft request' (duration: 755.913171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:34.174389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:10:33.418175Z","time spent":"756.152408ms","remote":"127.0.0.1:59888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:10:42.634044Z","caller":"traceutil/trace.go:171","msg":"trace[361366719] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"112.90712ms","start":"2026-07-22T22:10:42.52107Z","end":"2026-07-22T22:10:42.633977Z","steps":["trace[361366719] 'process raft request' (duration: 112.746125ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:42.747292Z","caller":"traceutil/trace.go:171","msg":"trace[1533689953] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"106.938032ms","start":"2026-07-22T22:10:42.640324Z","end":"2026-07-22T22:10:42.747262Z","steps":["trace[1533689953] 'process raft request' (duration: 106.21638ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:42.747486Z","caller":"traceutil/trace.go:171","msg":"trace[439870706] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"106.187198ms","start":"2026-07-22T22:10:42.641255Z","end":"2026-07-22T22:10:42.747442Z","steps":["trace[439870706] 'process raft request' (duration: 105.869908ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:42.747781Z","caller":"traceutil/trace.go:171","msg":"trace[417110244] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:933; }","duration":"100.25155ms","start":"2026-07-22T22:10:42.647516Z","end":"2026-07-22T22:10:42.747768Z","steps":["trace[417110244] 'read index received' (duration: 34.833091ms)","trace[417110244] 'applied index is now lower than readState.Index' (duration: 65.417599ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:10:42.74801Z","caller":"traceutil/trace.go:171","msg":"trace[1926689960] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"105.744835ms","start":"2026-07-22T22:10:42.642254Z","end":"2026-07-22T22:10:42.747999Z","steps":["trace[1926689960] 'process raft request' (duration: 104.9554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:42.748115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.579502ms","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-07-22T22:10:42.748163Z","caller":"traceutil/trace.go:171","msg":"trace[2135072614] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:904; }","duration":"100.668194ms","start":"2026-07-22T22:10:42.647479Z","end":"2026-07-22T22:10:42.748147Z","steps":["trace[2135072614] 'agreement among raft nodes before linearized reading' (duration: 100.570431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:10:56.797676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.160838ms","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-07-22T22:10:56.797767Z","caller":"traceutil/trace.go:171","msg":"trace[2094770494] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:973; }","duration":"137.304142ms","start":"2026-07-22T22:10:56.660445Z","end":"2026-07-22T22:10:56.797749Z","steps":["trace[2094770494] 'count revisions from in-memory index tree' (duration: 137.090475ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:10:57.277056Z","caller":"traceutil/trace.go:171","msg":"trace[432609441] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1010; }","duration":"101.365476ms","start":"2026-07-22T22:10:57.175668Z","end":"2026-07-22T22:10:57.277034Z","steps":["trace[432609441] 'read index received' (duration: 21.452588ms)","trace[432609441] 'applied index is now lower than readState.Index' (duration: 79.911798ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:10:57.277215Z","caller":"traceutil/trace.go:171","msg":"trace[20921543] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"104.157304ms","start":"2026-07-22T22:10:57.173045Z","end":"2026-07-22T22:10:57.277203Z","steps":["trace[20921543] 'process raft request' (duration: 93.61107ms)","trace[20921543] 'compare' (duration: 10.270695ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:10:57.277467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.759658ms","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-07-22T22:10:57.277585Z","caller":"traceutil/trace.go:171","msg":"trace[143219420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"101.907253ms","start":"2026-07-22T22:10:57.175652Z","end":"2026-07-22T22:10:57.277559Z","steps":["trace[143219420] 'agreement among raft nodes before linearized reading' (duration: 101.525401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:10.648602Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394882802670984,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:11:11.149697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394882802670984,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:11:11.650496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394882802670984,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:11:11.655608Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.887173324s","expected-duration":"1s"} {"level":"info","ts":"2026-07-22T22:11:11.656082Z","caller":"traceutil/trace.go:171","msg":"trace[1663331410] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.887720841s","start":"2026-07-22T22:11:09.768331Z","end":"2026-07-22T22:11:11.656052Z","steps":["trace[1663331410] 'process raft request' (duration: 1.887501895s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:11.656219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:09.76831Z","time spent":"1.887831345s","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:12.148397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000757636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-22T22:11:12.148524Z","caller":"traceutil/trace.go:171","msg":"trace[78388525] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000924172s","start":"2026-07-22T22:11:10.147573Z","end":"2026-07-22T22:11:12.148497Z","steps":["trace[78388525] 'agreement among raft nodes before linearized reading' (duration: 2.000789428s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:12.148584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:10.147541Z","time spent":"2.001030105s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/22 22:11:12 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-22T22:11:12.16475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.494533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:12.164947Z","caller":"traceutil/trace.go:171","msg":"trace[2048133097] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"1.47599991s","start":"2026-07-22T22:11:10.688917Z","end":"2026-07-22T22:11:12.164917Z","steps":["trace[2048133097] 'process raft request' (duration: 1.040209428s)","trace[2048133097] 'compare' (duration: 435.383349ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:12.165085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:10.688891Z","time spent":"1.476121614s","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:12.284094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.236591ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-22T22:11:12.284206Z","caller":"traceutil/trace.go:171","msg":"trace[2105185069] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1059; }","duration":"2.136555331s","start":"2026-07-22T22:11:10.147626Z","end":"2026-07-22T22:11:12.284181Z","steps":["trace[2105185069] 'read index received' (duration: 1.508194318s)","trace[2105185069] 'applied index is now lower than readState.Index' (duration: 628.359723ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:12.284416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684594057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-22T22:11:12.284452Z","caller":"traceutil/trace.go:171","msg":"trace[807686052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1022; }","duration":"1.684682689s","start":"2026-07-22T22:11:10.59976Z","end":"2026-07-22T22:11:12.284442Z","steps":["trace[807686052] 'agreement among raft nodes before linearized reading' (duration: 1.684527534s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:12.284501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:10.599734Z","time spent":"1.684757462s","remote":"127.0.0.1:59968","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-07-22T22:11:12.284521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.695775ms","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-07-22T22:11:12.284614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.236079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-22T22:11:12.284585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.701762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:12.284652Z","caller":"traceutil/trace.go:171","msg":"trace[1071087276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1022; }","duration":"991.312922ms","start":"2026-07-22T22:11:11.293329Z","end":"2026-07-22T22:11:12.284642Z","steps":["trace[1071087276] 'agreement among raft nodes before linearized reading' (duration: 991.197158ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:12.284618Z","caller":"traceutil/trace.go:171","msg":"trace[331922815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1022; }","duration":"930.835369ms","start":"2026-07-22T22:11:11.353758Z","end":"2026-07-22T22:11:12.284593Z","steps":["trace[331922815] 'agreement among raft nodes before linearized reading' (duration: 930.575131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:12.284679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:11.293304Z","time spent":"991.368254ms","remote":"127.0.0.1:59968","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-07-22T22:11:12.284676Z","caller":"traceutil/trace.go:171","msg":"trace[1600425511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"135.835666ms","start":"2026-07-22T22:11:12.148819Z","end":"2026-07-22T22:11:12.284654Z","steps":["trace[1600425511] 'agreement among raft nodes before linearized reading' (duration: 135.697692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:12.284703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:11.353741Z","time spent":"930.950213ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T22:11:12.28478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.982943ms","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-07-22T22:11:12.284827Z","caller":"traceutil/trace.go:171","msg":"trace[629345696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1022; }","duration":"931.070167ms","start":"2026-07-22T22:11:11.353743Z","end":"2026-07-22T22:11:12.284814Z","steps":["trace[629345696] 'agreement among raft nodes before linearized reading' (duration: 930.884091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:12.284855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:11.353725Z","time spent":"931.121298ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T22:11:12.28504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.769335ms","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-07-22T22:11:12.286072Z","caller":"traceutil/trace.go:171","msg":"trace[675258352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1022; }","duration":"936.839019ms","start":"2026-07-22T22:11:11.349217Z","end":"2026-07-22T22:11:12.286056Z","steps":["trace[675258352] 'agreement among raft nodes before linearized reading' (duration: 935.28031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:12.286156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:11.349195Z","time spent":"936.905481ms","remote":"127.0.0.1:59968","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-07-22T22:11:36.521901Z","caller":"traceutil/trace.go:171","msg":"trace[28632861] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"108.070958ms","start":"2026-07-22T22:11:36.413805Z","end":"2026-07-22T22:11:36.521876Z","steps":["trace[28632861] 'read index received' (duration: 17.413141ms)","trace[28632861] 'applied index is now lower than readState.Index' (duration: 90.656757ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:36.521983Z","caller":"traceutil/trace.go:171","msg":"trace[557033768] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"182.297906ms","start":"2026-07-22T22:11:36.339622Z","end":"2026-07-22T22:11:36.52192Z","steps":["trace[557033768] 'process raft request' (duration: 91.68862ms)","trace[557033768] 'compare' (duration: 90.42633ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:36.522166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.340597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-22T22:11:36.52217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.403699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-22T22:11:36.522196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.594673ms","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-07-22T22:11:36.522236Z","caller":"traceutil/trace.go:171","msg":"trace[1730609227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"108.44652ms","start":"2026-07-22T22:11:36.413771Z","end":"2026-07-22T22:11:36.522218Z","steps":["trace[1730609227] 'agreement among raft nodes before linearized reading' (duration: 108.227593ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:36.522245Z","caller":"traceutil/trace.go:171","msg":"trace[3354101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"107.686206ms","start":"2026-07-22T22:11:36.414547Z","end":"2026-07-22T22:11:36.522233Z","steps":["trace[3354101] 'agreement among raft nodes before linearized reading' (duration: 107.50249ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:36.522244Z","caller":"traceutil/trace.go:171","msg":"trace[979908979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1102; }","duration":"102.487621ms","start":"2026-07-22T22:11:36.419738Z","end":"2026-07-22T22:11:36.522225Z","steps":["trace[979908979] 'agreement among raft nodes before linearized reading' (duration: 102.327016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:36.522256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.991227ms","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-07-22T22:11:36.522315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.632568ms","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-07-22T22:11:36.52235Z","caller":"traceutil/trace.go:171","msg":"trace[1197685509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1102; }","duration":"103.130082ms","start":"2026-07-22T22:11:36.419186Z","end":"2026-07-22T22:11:36.522316Z","steps":["trace[1197685509] 'agreement among raft nodes before linearized reading' (duration: 102.903155ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:36.522376Z","caller":"traceutil/trace.go:171","msg":"trace[945465059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1102; }","duration":"103.711191ms","start":"2026-07-22T22:11:36.418649Z","end":"2026-07-22T22:11:36.52236Z","steps":["trace[945465059] 'agreement among raft nodes before linearized reading' (duration: 103.554136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:36.844522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.602113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:36.844744Z","caller":"traceutil/trace.go:171","msg":"trace[424870607] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1148; }","duration":"316.874381ms","start":"2026-07-22T22:11:36.527835Z","end":"2026-07-22T22:11:36.84471Z","steps":["trace[424870607] 'read index received' (duration: 47.524053ms)","trace[424870607] 'applied index is now lower than readState.Index' (duration: 269.349668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:36.84481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.971995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-22T22:11:36.844827Z","caller":"traceutil/trace.go:171","msg":"trace[162551339] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1107; }","duration":"317.015256ms","start":"2026-07-22T22:11:36.527805Z","end":"2026-07-22T22:11:36.844821Z","steps":["trace[162551339] 'agreement among raft nodes before linearized reading' (duration: 316.961094ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:36.844828Z","caller":"traceutil/trace.go:171","msg":"trace[1437042567] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"317.841022ms","start":"2026-07-22T22:11:36.526964Z","end":"2026-07-22T22:11:36.844805Z","steps":["trace[1437042567] 'process raft request' (duration: 317.652516ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:36.844877Z","caller":"traceutil/trace.go:171","msg":"trace[1580469217] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"317.753679ms","start":"2026-07-22T22:11:36.527118Z","end":"2026-07-22T22:11:36.844871Z","steps":["trace[1580469217] 'process raft request' (duration: 317.534653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:36.844913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:36.527109Z","time spent":"317.78494ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:36.844946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:36.52694Z","time spent":"317.940346ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:36.84485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:36.527795Z","time spent":"317.049038ms","remote":"127.0.0.1:59972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-07-22T22:11:36.84504Z","caller":"traceutil/trace.go:171","msg":"trace[101764801] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"316.894352ms","start":"2026-07-22T22:11:36.528072Z","end":"2026-07-22T22:11:36.844966Z","steps":["trace[101764801] 'process raft request' (duration: 316.603793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:36.845184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:36.528061Z","time spent":"317.056607ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:36.845048Z","caller":"traceutil/trace.go:171","msg":"trace[622402140] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"318.060979ms","start":"2026-07-22T22:11:36.526963Z","end":"2026-07-22T22:11:36.845024Z","steps":["trace[622402140] 'process raft request' (duration: 123.886638ms)","trace[622402140] 'compare' (duration: 193.411107ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:36.845258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:36.52694Z","time spent":"318.293417ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:36.908299Z","caller":"traceutil/trace.go:171","msg":"trace[848547981] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"317.897434ms","start":"2026-07-22T22:11:36.590376Z","end":"2026-07-22T22:11:36.908274Z","steps":["trace[848547981] 'process raft request' (duration: 317.76626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:36.908514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:36.590356Z","time spent":"318.014798ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:38.584182Z","caller":"traceutil/trace.go:171","msg":"trace[1989127246] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"329.489571ms","start":"2026-07-22T22:11:38.254675Z","end":"2026-07-22T22:11:38.584164Z","steps":["trace[1989127246] 'process raft request' (duration: 329.193181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:38.584286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:38.254649Z","time spent":"329.583853ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:38.750231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.377302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:38.750392Z","caller":"traceutil/trace.go:171","msg":"trace[302132438] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"162.471309ms","start":"2026-07-22T22:11:38.587845Z","end":"2026-07-22T22:11:38.750316Z","steps":["trace[302132438] 'process raft request' (duration: 56.934571ms)","trace[302132438] 'compare' (duration: 105.277959ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:38.974808Z","caller":"traceutil/trace.go:171","msg":"trace[573900327] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"122.467494ms","start":"2026-07-22T22:11:38.852305Z","end":"2026-07-22T22:11:38.974772Z","steps":["trace[573900327] 'process raft request' (duration: 84.445951ms)","trace[573900327] 'compare' (duration: 37.798756ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:39.038583Z","caller":"traceutil/trace.go:171","msg":"trace[664004492] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"185.208708ms","start":"2026-07-22T22:11:38.853348Z","end":"2026-07-22T22:11:39.038556Z","steps":["trace[664004492] 'process raft request' (duration: 185.114765ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:39.038636Z","caller":"traceutil/trace.go:171","msg":"trace[1816807384] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"185.798427ms","start":"2026-07-22T22:11:38.852816Z","end":"2026-07-22T22:11:39.038614Z","steps":["trace[1816807384] 'process raft request' (duration: 185.522288ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:39.038605Z","caller":"traceutil/trace.go:171","msg":"trace[685745579] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"184.992091ms","start":"2026-07-22T22:11:38.853595Z","end":"2026-07-22T22:11:39.038587Z","steps":["trace[685745579] 'process raft request' (duration: 184.918569ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:41.055508Z","caller":"traceutil/trace.go:171","msg":"trace[2127126321] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"302.020652ms","start":"2026-07-22T22:11:40.753472Z","end":"2026-07-22T22:11:41.055493Z","steps":["trace[2127126321] 'read index received' (duration: 301.886567ms)","trace[2127126321] 'applied index is now lower than readState.Index' (duration: 133.455µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:41.055619Z","caller":"traceutil/trace.go:171","msg":"trace[697350842] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"459.666397ms","start":"2026-07-22T22:11:40.595941Z","end":"2026-07-22T22:11:41.055607Z","steps":["trace[697350842] 'process raft request' (duration: 459.457501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:41.055695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:40.595921Z","time spent":"459.716239ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:41.05614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.666963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-22T22:11:41.056174Z","caller":"traceutil/trace.go:171","msg":"trace[1982769233] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1115; }","duration":"302.724765ms","start":"2026-07-22T22:11:40.753434Z","end":"2026-07-22T22:11:41.056159Z","steps":["trace[1982769233] 'agreement among raft nodes before linearized reading' (duration: 302.662143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:41.056198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:40.753416Z","time spent":"302.773176ms","remote":"127.0.0.1:59968","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-07-22T22:11:41.58995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.938657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:41.590054Z","caller":"traceutil/trace.go:171","msg":"trace[108237094] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"531.343014ms","start":"2026-07-22T22:11:41.058692Z","end":"2026-07-22T22:11:41.590035Z","steps":["trace[108237094] 'process raft request' (duration: 292.236032ms)","trace[108237094] 'compare' (duration: 238.749271ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:41.590113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:41.058678Z","time spent":"531.405807ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:42.144174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.374464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:42.144538Z","caller":"traceutil/trace.go:171","msg":"trace[1677439257] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"1.084775179s","start":"2026-07-22T22:11:41.059734Z","end":"2026-07-22T22:11:42.144509Z","steps":["trace[1677439257] 'process raft request' (duration: 701.999483ms)","trace[1677439257] 'compare' (duration: 382.199887ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:42.144682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:41.059723Z","time spent":"1.084884702s","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:42.144867Z","caller":"traceutil/trace.go:171","msg":"trace[511695142] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"1.084412496s","start":"2026-07-22T22:11:41.06044Z","end":"2026-07-22T22:11:42.144853Z","steps":["trace[511695142] 'process raft request' (duration: 1.084269712s)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:42.144835Z","caller":"traceutil/trace.go:171","msg":"trace[1941387879] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"1.08482322s","start":"2026-07-22T22:11:41.059784Z","end":"2026-07-22T22:11:42.144607Z","steps":["trace[1941387879] 'process raft request' (duration: 1.084493549s)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:42.145026Z","caller":"traceutil/trace.go:171","msg":"trace[228379210] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"1.083109915s","start":"2026-07-22T22:11:41.061893Z","end":"2026-07-22T22:11:42.145003Z","steps":["trace[228379210] 'process raft request' (duration: 1.082905889s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:42.145169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:41.061872Z","time spent":"1.08324016s","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:42.14506Z","caller":"traceutil/trace.go:171","msg":"trace[1473129027] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1160; }","duration":"997.041573ms","start":"2026-07-22T22:11:41.148003Z","end":"2026-07-22T22:11:42.145045Z","steps":["trace[1473129027] 'read index received' (duration: 202.948469ms)","trace[1473129027] 'applied index is now lower than readState.Index' (duration: 794.091144ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:42.145449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:41.060428Z","time spent":"1.084474149s","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:42.145544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:41.059765Z","time spent":"1.085729439s","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:42.328354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18032964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:42.328409Z","caller":"traceutil/trace.go:171","msg":"trace[488798633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1120; }","duration":"1.180431873s","start":"2026-07-22T22:11:41.147964Z","end":"2026-07-22T22:11:42.328396Z","steps":["trace[488798633] 'agreement among raft nodes before linearized reading' (duration: 997.121236ms)","trace[488798633] 'range keys from in-memory index tree' (duration: 183.185824ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:42.328437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:41.147948Z","time spent":"1.180484665s","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:11:42.328583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.600807ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-22T22:11:42.328784Z","caller":"traceutil/trace.go:171","msg":"trace[399429906] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"137.384705ms","start":"2026-07-22T22:11:42.191386Z","end":"2026-07-22T22:11:42.328771Z","steps":["trace[399429906] 'process raft request' (duration: 137.269311ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:42.328781Z","caller":"traceutil/trace.go:171","msg":"trace[1124954805] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1165; }","duration":"183.493633ms","start":"2026-07-22T22:11:42.145267Z","end":"2026-07-22T22:11:42.328761Z","steps":["trace[1124954805] 'read index received' (duration: 35.271µs)","trace[1124954805] 'applied index is now lower than readState.Index' (duration: 183.456482ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:42.328978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.653223ms","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-07-22T22:11:42.329063Z","caller":"traceutil/trace.go:171","msg":"trace[1754648209] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1121; }","duration":"819.846009ms","start":"2026-07-22T22:11:41.509211Z","end":"2026-07-22T22:11:42.329057Z","steps":["trace[1754648209] 'agreement among raft nodes before linearized reading' (duration: 819.611632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:42.329097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:41.509156Z","time spent":"819.921122ms","remote":"127.0.0.1:60094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:11:42.84256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.551934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:11:42.842616Z","caller":"traceutil/trace.go:171","msg":"trace[1361959360] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1121; }","duration":"207.661237ms","start":"2026-07-22T22:11:42.634943Z","end":"2026-07-22T22:11:42.842604Z","steps":["trace[1361959360] 'count revisions from in-memory index tree' (duration: 207.445961ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:43.538039Z","caller":"traceutil/trace.go:171","msg":"trace[479785854] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"373.865903ms","start":"2026-07-22T22:11:43.16415Z","end":"2026-07-22T22:11:43.538016Z","steps":["trace[479785854] 'read index received' (duration: 373.647896ms)","trace[479785854] 'applied index is now lower than readState.Index' (duration: 217.047µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:43.538121Z","caller":"traceutil/trace.go:171","msg":"trace[2137495305] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"477.659256ms","start":"2026-07-22T22:11:43.06043Z","end":"2026-07-22T22:11:43.538089Z","steps":["trace[2137495305] 'process raft request' (duration: 477.446509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:43.538263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.04849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-22T22:11:43.538282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:43.060401Z","time spent":"477.793891ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:43.538344Z","caller":"traceutil/trace.go:171","msg":"trace[1511605558] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1122; }","duration":"374.202104ms","start":"2026-07-22T22:11:43.164097Z","end":"2026-07-22T22:11:43.538299Z","steps":["trace[1511605558] 'agreement among raft nodes before linearized reading' (duration: 374.063759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:43.538419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:43.164077Z","time spent":"374.303047ms","remote":"127.0.0.1:60156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:11:43.539045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.520792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:11:43.539161Z","caller":"traceutil/trace.go:171","msg":"trace[659827019] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1122; }","duration":"126.706168ms","start":"2026-07-22T22:11:43.412418Z","end":"2026-07-22T22:11:43.539124Z","steps":["trace[659827019] 'agreement among raft nodes before linearized reading' (duration: 126.520792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:44.070033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.572929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:44.070146Z","caller":"traceutil/trace.go:171","msg":"trace[708761637] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1122; }","duration":"308.717984ms","start":"2026-07-22T22:11:43.761403Z","end":"2026-07-22T22:11:44.070121Z","steps":["trace[708761637] 'count revisions from in-memory index tree' (duration: 308.409954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:44.07021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:43.761349Z","time spent":"308.843078ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:11:44.070214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.198629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-22T22:11:44.070245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.532714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:44.070271Z","caller":"traceutil/trace.go:171","msg":"trace[1119386435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1122; }","duration":"475.303292ms","start":"2026-07-22T22:11:43.594953Z","end":"2026-07-22T22:11:44.070256Z","steps":["trace[1119386435] 'range keys from in-memory index tree' (duration: 475.041284ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:44.070302Z","caller":"traceutil/trace.go:171","msg":"trace[321448636] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1122; }","duration":"416.682658ms","start":"2026-07-22T22:11:43.653604Z","end":"2026-07-22T22:11:44.070287Z","steps":["trace[321448636] 'count revisions from in-memory index tree' (duration: 416.40732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:44.070343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:43.65358Z","time spent":"416.75206ms","remote":"127.0.0.1:51356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:11:44.070308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:43.59493Z","time spent":"475.372135ms","remote":"127.0.0.1:59968","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-07-22T22:11:44.649455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394882802671253,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T22:11:44.653934Z","caller":"traceutil/trace.go:171","msg":"trace[2114427353] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1168; }","duration":"505.454166ms","start":"2026-07-22T22:11:44.148455Z","end":"2026-07-22T22:11:44.65391Z","steps":["trace[2114427353] 'read index received' (duration: 504.856827ms)","trace[2114427353] 'applied index is now lower than readState.Index' (duration: 596.299µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:44.654136Z","caller":"traceutil/trace.go:171","msg":"trace[2031439556] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"579.413755ms","start":"2026-07-22T22:11:44.074708Z","end":"2026-07-22T22:11:44.654122Z","steps":["trace[2031439556] 'process raft request' (duration: 578.794645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:44.654259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.783597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:44.654314Z","caller":"traceutil/trace.go:171","msg":"trace[765748884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1123; }","duration":"505.894701ms","start":"2026-07-22T22:11:44.148402Z","end":"2026-07-22T22:11:44.654297Z","steps":["trace[765748884] 'agreement among raft nodes before linearized reading' (duration: 505.800458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:44.654287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:44.074687Z","time spent":"579.519879ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:44.654352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:44.148381Z","time spent":"505.963472ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:11:44.654426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.304433ms","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-07-22T22:11:44.6545Z","caller":"traceutil/trace.go:171","msg":"trace[1058434339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1123; }","duration":"500.418767ms","start":"2026-07-22T22:11:44.154063Z","end":"2026-07-22T22:11:44.654481Z","steps":["trace[1058434339] 'agreement among raft nodes before linearized reading' (duration: 500.167089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:44.654544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:44.154058Z","time spent":"500.477779ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T22:11:44.65468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.682892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:11:44.654719Z","caller":"traceutil/trace.go:171","msg":"trace[499791659] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1123; }","duration":"148.737174ms","start":"2026-07-22T22:11:44.50596Z","end":"2026-07-22T22:11:44.654697Z","steps":["trace[499791659] 'agreement among raft nodes before linearized reading' (duration: 148.689663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:44.6548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.262452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-22T22:11:44.654836Z","caller":"traceutil/trace.go:171","msg":"trace[2068130927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1123; }","duration":"500.297553ms","start":"2026-07-22T22:11:44.154528Z","end":"2026-07-22T22:11:44.654825Z","steps":["trace[2068130927] 'agreement among raft nodes before linearized reading' (duration: 500.172279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:44.654869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:44.154512Z","time spent":"500.350504ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-22T22:11:44.654948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.640364ms","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-07-22T22:11:44.655042Z","caller":"traceutil/trace.go:171","msg":"trace[173753021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1123; }","duration":"500.701236ms","start":"2026-07-22T22:11:44.154284Z","end":"2026-07-22T22:11:44.654986Z","steps":["trace[173753021] 'agreement among raft nodes before linearized reading' (duration: 500.51475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:44.655088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.154195ms","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":"warn","ts":"2026-07-22T22:11:44.65496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.856691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-22T22:11:44.655128Z","caller":"traceutil/trace.go:171","msg":"trace[1917372642] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1123; }","duration":"240.197987ms","start":"2026-07-22T22:11:44.41492Z","end":"2026-07-22T22:11:44.655118Z","steps":["trace[1917372642] 'agreement among raft nodes before linearized reading' (duration: 240.114774ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:44.655139Z","caller":"traceutil/trace.go:171","msg":"trace[2098988142] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1123; }","duration":"501.038717ms","start":"2026-07-22T22:11:44.154088Z","end":"2026-07-22T22:11:44.655126Z","steps":["trace[2098988142] 'agreement among raft nodes before linearized reading' (duration: 500.81061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:44.655094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:44.15427Z","time spent":"500.808199ms","remote":"127.0.0.1:59968","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-07-22T22:11:44.65518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:44.154085Z","time spent":"501.083478ms","remote":"127.0.0.1:59968","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-07-22T22:11:45.017602Z","caller":"traceutil/trace.go:171","msg":"trace[1296304961] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"357.834358ms","start":"2026-07-22T22:11:44.659733Z","end":"2026-07-22T22:11:45.017568Z","steps":["trace[1296304961] 'process raft request' (duration: 324.466063ms)","trace[1296304961] 'compare' (duration: 33.20314ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:45.017769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:44.659721Z","time spent":"357.979322ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:45.54366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394882802671257,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T22:11:45.843547Z","caller":"traceutil/trace.go:171","msg":"trace[228739364] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"1.183731428s","start":"2026-07-22T22:11:44.659792Z","end":"2026-07-22T22:11:45.843523Z","steps":["trace[228739364] 'process raft request' (duration: 1.183554682s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:45.843834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:44.659763Z","time spent":"1.183852802s","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:45.843893Z","caller":"traceutil/trace.go:171","msg":"trace[1972721611] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1170; }","duration":"801.171728ms","start":"2026-07-22T22:11:45.042694Z","end":"2026-07-22T22:11:45.843866Z","steps":["trace[1972721611] 'read index received' (duration: 800.646811ms)","trace[1972721611] 'applied index is now lower than readState.Index' (duration: 523.797µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:45.844012Z","caller":"traceutil/trace.go:171","msg":"trace[1927890650] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"1.183582623s","start":"2026-07-22T22:11:44.660414Z","end":"2026-07-22T22:11:45.843997Z","steps":["trace[1927890650] 'process raft request' (duration: 1.183349795s)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:45.844036Z","caller":"traceutil/trace.go:171","msg":"trace[1408578161] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"1.183921634s","start":"2026-07-22T22:11:44.660097Z","end":"2026-07-22T22:11:45.844019Z","steps":["trace[1408578161] 'process raft request' (duration: 1.183374906s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:45.845118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:44.660398Z","time spent":"1.184635587s","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:45.844089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.377905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T22:11:45.845259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:44.660089Z","time spent":"1.185077s","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:45.845315Z","caller":"traceutil/trace.go:171","msg":"trace[101121975] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1127; }","duration":"802.659095ms","start":"2026-07-22T22:11:45.042638Z","end":"2026-07-22T22:11:45.845297Z","steps":["trace[101121975] 'agreement among raft nodes before linearized reading' (duration: 801.387445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:45.845529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:45.042618Z","time spent":"802.897593ms","remote":"127.0.0.1:60052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:11:46.13655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.714802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:46.13669Z","caller":"traceutil/trace.go:171","msg":"trace[831652135] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"588.604276ms","start":"2026-07-22T22:11:45.548059Z","end":"2026-07-22T22:11:46.136663Z","steps":["trace[831652135] 'process raft request' (duration: 385.693059ms)","trace[831652135] 'compare' (duration: 202.38227ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:46.137368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:45.548034Z","time spent":"589.167953ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:46.41644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.723118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-22T22:11:46.4165Z","caller":"traceutil/trace.go:171","msg":"trace[1988100505] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:1128; }","duration":"132.841642ms","start":"2026-07-22T22:11:46.283646Z","end":"2026-07-22T22:11:46.416487Z","steps":["trace[1988100505] 'range keys from in-memory index tree' (duration: 132.524462ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:46.698119Z","caller":"traceutil/trace.go:171","msg":"trace[1577313478] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"167.058854ms","start":"2026-07-22T22:11:46.531021Z","end":"2026-07-22T22:11:46.69808Z","steps":["trace[1577313478] 'process raft request' (duration: 103.135092ms)","trace[1577313478] 'compare' (duration: 63.296782ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:12:02.447991Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394882802671492,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T22:12:02.724522Z","caller":"traceutil/trace.go:171","msg":"trace[909061382] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1280; }","duration":"776.772446ms","start":"2026-07-22T22:12:01.947733Z","end":"2026-07-22T22:12:02.724505Z","steps":["trace[909061382] 'read index received' (duration: 775.136355ms)","trace[909061382] 'applied index is now lower than readState.Index' (duration: 1.635451ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:02.724533Z","caller":"traceutil/trace.go:171","msg":"trace[1242424692] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"527.219194ms","start":"2026-07-22T22:12:02.197293Z","end":"2026-07-22T22:12:02.724512Z","steps":["trace[1242424692] 'process raft request' (duration: 527.10285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:02.724679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:02.197267Z","time spent":"527.346048ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:12:02.724792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.878254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:12:02.724842Z","caller":"traceutil/trace.go:171","msg":"trace[2043573481] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1231; }","duration":"145.931636ms","start":"2026-07-22T22:12:02.578897Z","end":"2026-07-22T22:12:02.724828Z","steps":["trace[2043573481] 'agreement among raft nodes before linearized reading' (duration: 145.855263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:02.724899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.456675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-22T22:12:02.724939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.748346ms","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-07-22T22:12:02.724968Z","caller":"traceutil/trace.go:171","msg":"trace[958030159] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1231; }","duration":"775.574019ms","start":"2026-07-22T22:12:01.94938Z","end":"2026-07-22T22:12:02.724954Z","steps":["trace[958030159] 'agreement among raft nodes before linearized reading' (duration: 775.390703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:02.725002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:01.949364Z","time spent":"775.631041ms","remote":"127.0.0.1:59968","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-07-22T22:12:02.725Z","caller":"traceutil/trace.go:171","msg":"trace[726436537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1231; }","duration":"776.86223ms","start":"2026-07-22T22:12:01.948124Z","end":"2026-07-22T22:12:02.724986Z","steps":["trace[726436537] 'agreement among raft nodes before linearized reading' (duration: 776.630862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:02.725028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:01.948108Z","time spent":"776.914792ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T22:12:02.725299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.574132ms","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-07-22T22:12:02.725326Z","caller":"traceutil/trace.go:171","msg":"trace[1052299877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1231; }","duration":"777.638864ms","start":"2026-07-22T22:12:01.947679Z","end":"2026-07-22T22:12:02.725318Z","steps":["trace[1052299877] 'agreement among raft nodes before linearized reading' (duration: 776.926752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:02.725344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:01.947661Z","time spent":"777.678686ms","remote":"127.0.0.1:59968","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-07-22T22:12:03.600524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394882802671501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:12:04.108716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394882802671501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:12:04.609396Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394882802671501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:12:04.810097Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.934195761s","expected-duration":"1s"} {"level":"info","ts":"2026-07-22T22:12:04.81046Z","caller":"traceutil/trace.go:171","msg":"trace[1250363889] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"1.710771295s","start":"2026-07-22T22:12:03.099669Z","end":"2026-07-22T22:12:04.81044Z","steps":["trace[1250363889] 'read index received' (duration: 1.710641811s)","trace[1250363889] 'applied index is now lower than readState.Index' (duration: 128.754µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:04.810512Z","caller":"traceutil/trace.go:171","msg":"trace[1497155935] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"1.934662295s","start":"2026-07-22T22:12:02.875831Z","end":"2026-07-22T22:12:04.810494Z","steps":["trace[1497155935] 'process raft request' (duration: 1.93449342s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:04.810614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710944521s","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-07-22T22:12:04.810637Z","caller":"traceutil/trace.go:171","msg":"trace[404870357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"1.711009363s","start":"2026-07-22T22:12:03.09962Z","end":"2026-07-22T22:12:04.810629Z","steps":["trace[404870357] 'agreement among raft nodes before linearized reading' (duration: 1.710906469s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:04.810658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:03.0996Z","time spent":"1.711052643s","remote":"127.0.0.1:59968","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-07-22T22:12:04.810704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:02.875816Z","time spent":"1.934759349s","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:12:04.813316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664688368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:12:04.813364Z","caller":"traceutil/trace.go:171","msg":"trace[269561809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"1.66476535s","start":"2026-07-22T22:12:03.148585Z","end":"2026-07-22T22:12:04.813351Z","steps":["trace[269561809] 'agreement among raft nodes before linearized reading' (duration: 1.664665146s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:04.813394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:03.148573Z","time spent":"1.664815642s","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:12:04.813859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.320857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:12:04.813892Z","caller":"traceutil/trace.go:171","msg":"trace[1563010790] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1236; }","duration":"214.41111ms","start":"2026-07-22T22:12:04.599472Z","end":"2026-07-22T22:12:04.813883Z","steps":["trace[1563010790] 'agreement among raft nodes before linearized reading' (duration: 214.344007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:04.813998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.819469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:12:04.814018Z","caller":"traceutil/trace.go:171","msg":"trace[2145282535] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1236; }","duration":"273.892662ms","start":"2026-07-22T22:12:04.540119Z","end":"2026-07-22T22:12:04.814012Z","steps":["trace[2145282535] 'agreement among raft nodes before linearized reading' (duration: 273.862611ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:17.928887Z","caller":"traceutil/trace.go:171","msg":"trace[483915739] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"108.378038ms","start":"2026-07-22T22:12:17.82049Z","end":"2026-07-22T22:12:17.928868Z","steps":["trace[483915739] 'process raft request' (duration: 108.283115ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:19.222404Z","caller":"traceutil/trace.go:171","msg":"trace[674404758] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"352.841459ms","start":"2026-07-22T22:12:18.869545Z","end":"2026-07-22T22:12:19.222387Z","steps":["trace[674404758] 'read index received' (duration: 352.716685ms)","trace[674404758] 'applied index is now lower than readState.Index' (duration: 124.104µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:19.222492Z","caller":"traceutil/trace.go:171","msg":"trace[2037610265] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"370.256589ms","start":"2026-07-22T22:12:18.852229Z","end":"2026-07-22T22:12:19.222486Z","steps":["trace[2037610265] 'process raft request' (duration: 370.046382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:19.22258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:18.852212Z","time spent":"370.29643ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:12:19.222693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.720994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-22T22:12:19.222708Z","caller":"traceutil/trace.go:171","msg":"trace[2076958773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1282; }","duration":"278.757046ms","start":"2026-07-22T22:12:18.943946Z","end":"2026-07-22T22:12:19.222704Z","steps":["trace[2076958773] 'agreement among raft nodes before linearized reading' (duration: 278.706974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:19.222716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.158028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-22T22:12:19.222731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.546207ms","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-07-22T22:12:19.222736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.914318ms","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-07-22T22:12:19.222778Z","caller":"traceutil/trace.go:171","msg":"trace[31976865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1282; }","duration":"353.247831ms","start":"2026-07-22T22:12:18.869513Z","end":"2026-07-22T22:12:19.222761Z","steps":["trace[31976865] 'agreement among raft nodes before linearized reading' (duration: 353.061415ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:19.222787Z","caller":"traceutil/trace.go:171","msg":"trace[1522179492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1282; }","duration":"277.64235ms","start":"2026-07-22T22:12:18.94513Z","end":"2026-07-22T22:12:19.222772Z","steps":["trace[1522179492] 'agreement among raft nodes before linearized reading' (duration: 277.437703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:19.222824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:18.869499Z","time spent":"353.312853ms","remote":"127.0.0.1:59968","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-07-22T22:12:19.222824Z","caller":"traceutil/trace.go:171","msg":"trace[1438851107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1282; }","duration":"321.045233ms","start":"2026-07-22T22:12:18.901755Z","end":"2026-07-22T22:12:19.222801Z","steps":["trace[1438851107] 'agreement among raft nodes before linearized reading' (duration: 320.771904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:19.222877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:18.901741Z","time spent":"321.126245ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T22:12:19.222963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.508261ms","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-07-22T22:12:19.222979Z","caller":"traceutil/trace.go:171","msg":"trace[1015959124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1282; }","duration":"286.553032ms","start":"2026-07-22T22:12:18.936422Z","end":"2026-07-22T22:12:19.222975Z","steps":["trace[1015959124] 'agreement among raft nodes before linearized reading' (duration: 286.511101ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:19.447032Z","caller":"traceutil/trace.go:171","msg":"trace[1701708853] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"218.987836ms","start":"2026-07-22T22:12:19.228017Z","end":"2026-07-22T22:12:19.447005Z","steps":["trace[1701708853] 'process raft request' (duration: 184.880598ms)","trace[1701708853] 'compare' (duration: 33.890071ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:19.447349Z","caller":"traceutil/trace.go:171","msg":"trace[1503991037] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"219.197372ms","start":"2026-07-22T22:12:19.228127Z","end":"2026-07-22T22:12:19.447324Z","steps":["trace[1503991037] 'process raft request' (duration: 218.838581ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:19.44736Z","caller":"traceutil/trace.go:171","msg":"trace[1160609243] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1338; }","duration":"148.318161ms","start":"2026-07-22T22:12:19.299015Z","end":"2026-07-22T22:12:19.447333Z","steps":["trace[1160609243] 'read index received' (duration: 113.901092ms)","trace[1160609243] 'applied index is now lower than readState.Index' (duration: 34.416069ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:19.447359Z","caller":"traceutil/trace.go:171","msg":"trace[2043140426] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"219.070689ms","start":"2026-07-22T22:12:19.228265Z","end":"2026-07-22T22:12:19.447335Z","steps":["trace[2043140426] 'process raft request' (duration: 218.927564ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:19.447485Z","caller":"traceutil/trace.go:171","msg":"trace[673898690] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"219.162811ms","start":"2026-07-22T22:12:19.22831Z","end":"2026-07-22T22:12:19.447473Z","steps":["trace[673898690] 'process raft request' (duration: 218.971935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:19.447638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.578589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:12:19.447717Z","caller":"traceutil/trace.go:171","msg":"trace[1926707428] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1287; }","duration":"148.692912ms","start":"2026-07-22T22:12:19.299007Z","end":"2026-07-22T22:12:19.4477Z","steps":["trace[1926707428] 'agreement among raft nodes before linearized reading' (duration: 148.534977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:21.785512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394882802671636,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:12:22.285636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394882802671636,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:12:22.291497Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.0610645s","expected-duration":"1s"} {"level":"info","ts":"2026-07-22T22:12:22.291911Z","caller":"traceutil/trace.go:171","msg":"trace[1579170549] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"1.061575636s","start":"2026-07-22T22:12:21.230303Z","end":"2026-07-22T22:12:22.291878Z","steps":["trace[1579170549] 'process raft request' (duration: 1.061393881s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:22.292084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:21.230278Z","time spent":"1.06171237s","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:12:22.3465Z","caller":"traceutil/trace.go:171","msg":"trace[1349653597] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1342; }","duration":"1.061339208s","start":"2026-07-22T22:12:21.28514Z","end":"2026-07-22T22:12:22.346479Z","steps":["trace[1349653597] 'read index received' (duration: 1.006517644s)","trace[1349653597] 'applied index is now lower than readState.Index' (duration: 54.820594ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:12:22.346721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061568926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-22T22:12:22.346767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.444749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-22T22:12:22.346785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.446509ms","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-07-22T22:12:22.346786Z","caller":"traceutil/trace.go:171","msg":"trace[1587100185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"1.061686089s","start":"2026-07-22T22:12:21.285085Z","end":"2026-07-22T22:12:22.346771Z","steps":["trace[1587100185] 'agreement among raft nodes before linearized reading' (duration: 1.061490633s)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:22.346806Z","caller":"traceutil/trace.go:171","msg":"trace[575900756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"893.48515ms","start":"2026-07-22T22:12:21.453314Z","end":"2026-07-22T22:12:22.346799Z","steps":["trace[575900756] 'agreement among raft nodes before linearized reading' (duration: 893.417328ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:22.346803Z","caller":"traceutil/trace.go:171","msg":"trace[1834837121] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1288; }","duration":"893.512311ms","start":"2026-07-22T22:12:21.453282Z","end":"2026-07-22T22:12:22.346794Z","steps":["trace[1834837121] 'agreement among raft nodes before linearized reading' (duration: 893.367377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:22.346815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:21.285063Z","time spent":"1.061746172s","remote":"127.0.0.1:59968","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-07-22T22:12:22.346827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:21.453309Z","time spent":"893.514371ms","remote":"127.0.0.1:59968","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-07-22T22:12:22.346828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:21.453274Z","time spent":"893.548552ms","remote":"127.0.0.1:59968","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-07-22T22:12:22.346723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.953427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:12:22.346897Z","caller":"traceutil/trace.go:171","msg":"trace[655579916] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1288; }","duration":"208.188914ms","start":"2026-07-22T22:12:22.138701Z","end":"2026-07-22T22:12:22.34689Z","steps":["trace[655579916] 'agreement among raft nodes before linearized reading' (duration: 207.934276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:22.346872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.933366ms","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-07-22T22:12:22.346943Z","caller":"traceutil/trace.go:171","msg":"trace[1650480430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1288; }","duration":"895.047509ms","start":"2026-07-22T22:12:21.451878Z","end":"2026-07-22T22:12:22.346925Z","steps":["trace[1650480430] 'agreement among raft nodes before linearized reading' (duration: 894.797611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:22.346954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.698197ms","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-07-22T22:12:22.346984Z","caller":"traceutil/trace.go:171","msg":"trace[1652902800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"893.772899ms","start":"2026-07-22T22:12:21.453203Z","end":"2026-07-22T22:12:22.346975Z","steps":["trace[1652902800] 'agreement among raft nodes before linearized reading' (duration: 893.687686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:22.346989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.721845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T22:12:22.346997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:21.451861Z","time spent":"895.116172ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T22:12:22.347008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:21.453178Z","time spent":"893.823281ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-22T22:12:22.347013Z","caller":"traceutil/trace.go:171","msg":"trace[1963113363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"198.784927ms","start":"2026-07-22T22:12:22.148221Z","end":"2026-07-22T22:12:22.347006Z","steps":["trace[1963113363] 'agreement among raft nodes before linearized reading' (duration: 198.751346ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:22.6486Z","caller":"traceutil/trace.go:171","msg":"trace[1659244474] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"297.25377ms","start":"2026-07-22T22:12:22.351316Z","end":"2026-07-22T22:12:22.648569Z","steps":["trace[1659244474] 'process raft request' (duration: 297.032153ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:22.648617Z","caller":"traceutil/trace.go:171","msg":"trace[762036492] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"297.2462ms","start":"2026-07-22T22:12:22.351346Z","end":"2026-07-22T22:12:22.648592Z","steps":["trace[762036492] 'process raft request' (duration: 297.141816ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:22.648886Z","caller":"traceutil/trace.go:171","msg":"trace[1547341120] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"297.482386ms","start":"2026-07-22T22:12:22.351393Z","end":"2026-07-22T22:12:22.648875Z","steps":["trace[1547341120] 'process raft request' (duration: 297.156096ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:22.648909Z","caller":"traceutil/trace.go:171","msg":"trace[1964405343] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"294.915976ms","start":"2026-07-22T22:12:22.353976Z","end":"2026-07-22T22:12:22.648892Z","steps":["trace[1964405343] 'process raft request' (duration: 294.827893ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:24.723248Z","caller":"traceutil/trace.go:171","msg":"trace[965897514] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"293.835123ms","start":"2026-07-22T22:12:24.429383Z","end":"2026-07-22T22:12:24.723218Z","steps":["trace[965897514] 'read index received' (duration: 293.639697ms)","trace[965897514] 'applied index is now lower than readState.Index' (duration: 193.896µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:24.723406Z","caller":"traceutil/trace.go:171","msg":"trace[914305263] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"426.031834ms","start":"2026-07-22T22:12:24.29736Z","end":"2026-07-22T22:12:24.723392Z","steps":["trace[914305263] 'process raft request' (duration: 425.642442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:24.723612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:24.297335Z","time spent":"426.109767ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:12:24.723745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.371271ms","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-07-22T22:12:24.72381Z","caller":"traceutil/trace.go:171","msg":"trace[141367286] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1296; }","duration":"294.492284ms","start":"2026-07-22T22:12:24.429301Z","end":"2026-07-22T22:12:24.723793Z","steps":["trace[141367286] 'agreement among raft nodes before linearized reading' (duration: 294.322398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:24.723671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.90101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:12:24.723924Z","caller":"traceutil/trace.go:171","msg":"trace[1876377919] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1296; }","duration":"254.190029ms","start":"2026-07-22T22:12:24.469705Z","end":"2026-07-22T22:12:24.723895Z","steps":["trace[1876377919] 'agreement among raft nodes before linearized reading' (duration: 253.883569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:25.45704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.629967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:12:25.457238Z","caller":"traceutil/trace.go:171","msg":"trace[1454852340] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"729.296986ms","start":"2026-07-22T22:12:24.727921Z","end":"2026-07-22T22:12:25.457218Z","steps":["trace[1454852340] 'process raft request' (duration: 729.205873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:25.457313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:24.7279Z","time spent":"729.375268ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:12:25.457523Z","caller":"traceutil/trace.go:171","msg":"trace[2088932435] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"729.562484ms","start":"2026-07-22T22:12:24.727921Z","end":"2026-07-22T22:12:25.457484Z","steps":["trace[2088932435] 'process raft request' (duration: 97.406441ms)","trace[2088932435] 'compare' (duration: 631.323037ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:12:25.457715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:24.7279Z","time spent":"729.699158ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:12:25.488187Z","caller":"traceutil/trace.go:171","msg":"trace[431138399] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"759.954015ms","start":"2026-07-22T22:12:24.728211Z","end":"2026-07-22T22:12:25.488165Z","steps":["trace[431138399] 'process raft request' (duration: 759.811641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:25.488317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:24.728184Z","time spent":"760.069018ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:12:25.488584Z","caller":"traceutil/trace.go:171","msg":"trace[2099316151] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1354; }","duration":"339.412405ms","start":"2026-07-22T22:12:25.149143Z","end":"2026-07-22T22:12:25.488555Z","steps":["trace[2099316151] 'read index received' (duration: 338.813316ms)","trace[2099316151] 'applied index is now lower than readState.Index' (duration: 597.819µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:25.488664Z","caller":"traceutil/trace.go:171","msg":"trace[219844015] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"760.677828ms","start":"2026-07-22T22:12:24.727974Z","end":"2026-07-22T22:12:25.488652Z","steps":["trace[219844015] 'process raft request' (duration: 760.151091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:25.488751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:24.727949Z","time spent":"760.74636ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:12:25.488793Z","caller":"traceutil/trace.go:171","msg":"trace[855059994] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"759.861913ms","start":"2026-07-22T22:12:24.72891Z","end":"2026-07-22T22:12:25.488772Z","steps":["trace[855059994] 'process raft request' (duration: 759.526522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:25.488807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.652072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:12:25.48886Z","caller":"traceutil/trace.go:171","msg":"trace[1296325910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"339.755266ms","start":"2026-07-22T22:12:25.149091Z","end":"2026-07-22T22:12:25.488846Z","steps":["trace[1296325910] 'agreement among raft nodes before linearized reading' (duration: 339.57791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:25.488901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:25.149069Z","time spent":"339.821617ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:12:25.488933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:24.728899Z","time spent":"759.943165ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:12:26.786545Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394882802671666,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T22:12:26.884174Z","caller":"traceutil/trace.go:171","msg":"trace[1005528105] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"675.293548ms","start":"2026-07-22T22:12:26.208857Z","end":"2026-07-22T22:12:26.88415Z","steps":["trace[1005528105] 'process raft request' (duration: 675.106862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:26.884325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:26.208832Z","time spent":"675.412492ms","remote":"127.0.0.1:59792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:12:27.60345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.355388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:12:27.603549Z","caller":"traceutil/trace.go:171","msg":"trace[207996081] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1359; }","duration":"1.317854051s","start":"2026-07-22T22:12:26.285679Z","end":"2026-07-22T22:12:27.603533Z","steps":["trace[207996081] 'read index received' (duration: 598.218051ms)","trace[207996081] 'applied index is now lower than readState.Index' (duration: 719.6349ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:12:27.603906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199785536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:12:27.603975Z","caller":"traceutil/trace.go:171","msg":"trace[1460771384] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1305; }","duration":"1.199884239s","start":"2026-07-22T22:12:26.404073Z","end":"2026-07-22T22:12:27.603958Z","steps":["trace[1460771384] 'agreement among raft nodes before linearized reading' (duration: 1.199751675s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:27.603976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.852226ms","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-07-22T22:12:27.604028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:26.404059Z","time spent":"1.199952831s","remote":"127.0.0.1:59874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:12:27.604054Z","caller":"traceutil/trace.go:171","msg":"trace[910152891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1305; }","duration":"110.97661ms","start":"2026-07-22T22:12:27.493066Z","end":"2026-07-22T22:12:27.604043Z","steps":["trace[910152891] 'agreement among raft nodes before linearized reading' (duration: 110.801844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:27.604066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.575397ms","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-07-22T22:12:27.604104Z","caller":"traceutil/trace.go:171","msg":"trace[435020930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1305; }","duration":"141.656111ms","start":"2026-07-22T22:12:27.462438Z","end":"2026-07-22T22:12:27.604094Z","steps":["trace[435020930] 'agreement among raft nodes before linearized reading' (duration: 141.492585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:27.604111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.023231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T22:12:27.604158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31846738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-22T22:12:27.604182Z","caller":"traceutil/trace.go:171","msg":"trace[613532675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"455.129645ms","start":"2026-07-22T22:12:27.149034Z","end":"2026-07-22T22:12:27.604163Z","steps":["trace[613532675] 'agreement among raft nodes before linearized reading' (duration: 454.9941ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:27.60422Z","caller":"traceutil/trace.go:171","msg":"trace[1803777737] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"874.770256ms","start":"2026-07-22T22:12:26.729443Z","end":"2026-07-22T22:12:27.604213Z","steps":["trace[1803777737] 'process raft request' (duration: 727.566011ms)","trace[1803777737] 'compare' (duration: 146.195963ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:12:27.604213Z","caller":"traceutil/trace.go:171","msg":"trace[2004955130] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:1305; }","duration":"1.318549853s","start":"2026-07-22T22:12:26.285647Z","end":"2026-07-22T22:12:27.604197Z","steps":["trace[2004955130] 'agreement among raft nodes before linearized reading' (duration: 1.318397048s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:27.604228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:27.149019Z","time spent":"455.200937ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:12:27.60426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:26.285632Z","time spent":"1.318616164s","remote":"127.0.0.1:59762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"warn","ts":"2026-07-22T22:12:27.604279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:26.72941Z","time spent":"874.829048ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:12:27.604269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.279413ms","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-07-22T22:12:27.604316Z","caller":"traceutil/trace.go:171","msg":"trace[1127948041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1305; }","duration":"107.350875ms","start":"2026-07-22T22:12:27.496952Z","end":"2026-07-22T22:12:27.604303Z","steps":["trace[1127948041] 'agreement among raft nodes before linearized reading' (duration: 107.225091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:27.604321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159041917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T22:12:27.604342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.812565ms","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-07-22T22:12:27.604374Z","caller":"traceutil/trace.go:171","msg":"trace[2067202912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1305; }","duration":"141.883027ms","start":"2026-07-22T22:12:27.462483Z","end":"2026-07-22T22:12:27.604366Z","steps":["trace[2067202912] 'agreement among raft nodes before linearized reading' (duration: 141.796164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:27.604193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.18852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-22T22:12:27.604414Z","caller":"traceutil/trace.go:171","msg":"trace[807263201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1305; }","duration":"107.421387ms","start":"2026-07-22T22:12:27.496987Z","end":"2026-07-22T22:12:27.604409Z","steps":["trace[807263201] 'agreement among raft nodes before linearized reading' (duration: 107.17004ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:27.604372Z","caller":"traceutil/trace.go:171","msg":"trace[1782835116] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1305; }","duration":"1.15912678s","start":"2026-07-22T22:12:26.445229Z","end":"2026-07-22T22:12:27.604356Z","steps":["trace[1782835116] 'agreement among raft nodes before linearized reading' (duration: 1.159041437s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:27.604465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:26.445214Z","time spent":"1.159241294s","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-22T22:12:28.091784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.269966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:12:28.092171Z","caller":"traceutil/trace.go:171","msg":"trace[1999702620] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1362; }","duration":"340.021003ms","start":"2026-07-22T22:12:27.752131Z","end":"2026-07-22T22:12:28.092152Z","steps":["trace[1999702620] 'read index received' (duration: 172.248407ms)","trace[1999702620] 'applied index is now lower than readState.Index' (duration: 167.771486ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:12:28.092314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.177989ms","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-07-22T22:12:28.092358Z","caller":"traceutil/trace.go:171","msg":"trace[1057854138] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1311; }","duration":"340.260871ms","start":"2026-07-22T22:12:27.752083Z","end":"2026-07-22T22:12:28.092344Z","steps":["trace[1057854138] 'agreement among raft nodes before linearized reading' (duration: 340.134707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:28.092398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:27.752063Z","time spent":"340.322714ms","remote":"127.0.0.1:39578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-07-22T22:12:28.092627Z","caller":"traceutil/trace.go:171","msg":"trace[474625832] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"482.324185ms","start":"2026-07-22T22:12:27.610282Z","end":"2026-07-22T22:12:28.092606Z","steps":["trace[474625832] 'process raft request' (duration: 114.145251ms)","trace[474625832] 'compare' (duration: 366.974685ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:12:28.092762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:27.610256Z","time spent":"482.420277ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:12:28.093071Z","caller":"traceutil/trace.go:171","msg":"trace[533984440] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"482.683016ms","start":"2026-07-22T22:12:27.610371Z","end":"2026-07-22T22:12:28.093054Z","steps":["trace[533984440] 'process raft request' (duration: 481.525669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:28.093172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:27.610362Z","time spent":"482.759848ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:12:28.093371Z","caller":"traceutil/trace.go:171","msg":"trace[1228617354] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"482.932833ms","start":"2026-07-22T22:12:27.610421Z","end":"2026-07-22T22:12:28.093354Z","steps":["trace[1228617354] 'process raft request' (duration: 481.549759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:28.093458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:27.610413Z","time spent":"483.002765ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:12:28.093634Z","caller":"traceutil/trace.go:171","msg":"trace[131229186] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"483.050777ms","start":"2026-07-22T22:12:27.610567Z","end":"2026-07-22T22:12:28.093618Z","steps":["trace[131229186] 'process raft request' (duration: 481.442696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:28.09371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:27.610558Z","time spent":"483.115289ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:12:28.093876Z","caller":"traceutil/trace.go:171","msg":"trace[1564656715] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"482.559211ms","start":"2026-07-22T22:12:27.611303Z","end":"2026-07-22T22:12:28.093863Z","steps":["trace[1564656715] 'process raft request' (duration: 480.763364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:28.093965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:27.611294Z","time spent":"482.628164ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:12:28.094143Z","caller":"traceutil/trace.go:171","msg":"trace[1246469035] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"456.8998ms","start":"2026-07-22T22:12:27.637227Z","end":"2026-07-22T22:12:28.094126Z","steps":["trace[1246469035] 'process raft request' (duration: 454.883276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:12:28.094227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:12:27.637203Z","time spent":"456.983423ms","remote":"127.0.0.1:59762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:12:35.736991Z","caller":"traceutil/trace.go:171","msg":"trace[1140702198] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"106.593251ms","start":"2026-07-22T22:12:35.630373Z","end":"2026-07-22T22:12:35.736966Z","steps":["trace[1140702198] 'process raft request' (duration: 106.418656ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:16:48.690425Z","caller":"traceutil/trace.go:171","msg":"trace[306065779] linearizableReadLoop","detail":"{readStateIndex:2683; appliedIndex:2682; }","duration":"200.973854ms","start":"2026-07-22T22:16:48.489427Z","end":"2026-07-22T22:16:48.690401Z","steps":["trace[306065779] 'read index received' (duration: 200.750997ms)","trace[306065779] 'applied index is now lower than readState.Index' (duration: 221.887µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:16:48.69062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.16333ms","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-07-22T22:16:48.690663Z","caller":"traceutil/trace.go:171","msg":"trace[1203968122] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2573; }","duration":"201.243602ms","start":"2026-07-22T22:16:48.489409Z","end":"2026-07-22T22:16:48.690652Z","steps":["trace[1203968122] 'agreement among raft nodes before linearized reading' (duration: 201.100368ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:16:48.691599Z","caller":"traceutil/trace.go:171","msg":"trace[2127754003] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"210.91062ms","start":"2026-07-22T22:16:48.480653Z","end":"2026-07-22T22:16:48.691564Z","steps":["trace[2127754003] 'process raft request' (duration: 209.594968ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:16:48.694567Z","caller":"traceutil/trace.go:171","msg":"trace[1291685424] transaction","detail":"{read_only:false; response_revision:2574; number_of_response:1; }","duration":"101.302433ms","start":"2026-07-22T22:16:48.593252Z","end":"2026-07-22T22:16:48.694554Z","steps":["trace[1291685424] 'process raft request' (duration: 101.252411ms)"],"step_count":1}