{"level":"warn","ts":"2026-06-15T19:06:27.549679Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T19:06:27.549769Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.249:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.249:2380","--initial-cluster=instance=https://199.19.213.249:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.249:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.249: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-06-15T19:06:27.549844Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T19:06:27.549859Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.249:2380"]} {"level":"info","ts":"2026-06-15T19:06:27.549877Z","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-06-15T19:06:27.550486Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"]} {"level":"info","ts":"2026-06-15T19:06:27.550554Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.249:2380"],"listen-peer-urls":["https://199.19.213.249:2380"],"advertise-client-urls":["https://199.19.213.249:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.249: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-06-15T19:06:27.55538Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.630189ms"} {"level":"info","ts":"2026-06-15T19:06:27.562018Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5835264b86215850","cluster-id":"4e10f3807bdbbd9b"} {"level":"info","ts":"2026-06-15T19:06:27.562085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=()"} {"level":"info","ts":"2026-06-15T19:06:27.562108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became follower at term 0"} {"level":"info","ts":"2026-06-15T19:06:27.562123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5835264b86215850 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-15T19:06:27.562134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became follower at term 1"} {"level":"info","ts":"2026-06-15T19:06:27.562168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=(6356028554918058064)"} {"level":"warn","ts":"2026-06-15T19:06:27.570247Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-15T19:06:27.572791Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-15T19:06:27.57411Z","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-06-15T19:06:27.577Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5835264b86215850","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-15T19:06:27.577279Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5835264b86215850","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-15T19:06:27.577541Z","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-06-15T19:06:27.577628Z","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-06-15T19:06:27.57768Z","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-06-15T19:06:27.578692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=(6356028554918058064)"} {"level":"info","ts":"2026-06-15T19:06:27.579052Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4e10f3807bdbbd9b","local-member-id":"5835264b86215850","added-peer-id":"5835264b86215850","added-peer-peer-urls":["https://199.19.213.249:2380"]} {"level":"info","ts":"2026-06-15T19:06:27.580955Z","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-06-15T19:06:27.581177Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5835264b86215850","initial-advertise-peer-urls":["https://199.19.213.249:2380"],"listen-peer-urls":["https://199.19.213.249:2380"],"advertise-client-urls":["https://199.19.213.249:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-15T19:06:27.581198Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.249:2380"} {"level":"info","ts":"2026-06-15T19:06:27.581295Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.249:2380"} {"level":"info","ts":"2026-06-15T19:06:27.581399Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-15T19:06:27.862844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 is starting a new election at term 1"} {"level":"info","ts":"2026-06-15T19:06:27.862925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-15T19:06:27.862961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 received MsgPreVoteResp from 5835264b86215850 at term 1"} {"level":"info","ts":"2026-06-15T19:06:27.862987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became candidate at term 2"} {"level":"info","ts":"2026-06-15T19:06:27.863004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 received MsgVoteResp from 5835264b86215850 at term 2"} {"level":"info","ts":"2026-06-15T19:06:27.863022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became leader at term 2"} {"level":"info","ts":"2026-06-15T19:06:27.863041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5835264b86215850 elected leader 5835264b86215850 at term 2"} {"level":"info","ts":"2026-06-15T19:06:27.864091Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5835264b86215850","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.249:2379]}","request-path":"/0/members/5835264b86215850/attributes","cluster-id":"4e10f3807bdbbd9b","publish-timeout":"7s"} {"level":"info","ts":"2026-06-15T19:06:27.864259Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T19:06:27.864347Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-15T19:06:27.864393Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-15T19:06:27.864227Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T19:06:27.864121Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T19:06:27.865403Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4e10f3807bdbbd9b","local-member-id":"5835264b86215850","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T19:06:27.865509Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T19:06:27.865573Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T19:06:27.869968Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.249:2379"} {"level":"info","ts":"2026-06-15T19:06:27.871307Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-15T19:07:03.648038Z","caller":"traceutil/trace.go:171","msg":"trace[1592111315] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"285.240787ms","start":"2026-06-15T19:07:03.362755Z","end":"2026-06-15T19:07:03.647995Z","steps":["trace[1592111315] 'process raft request' (duration: 281.364574ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:03.648098Z","caller":"traceutil/trace.go:171","msg":"trace[219333622] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:538; }","duration":"236.141755ms","start":"2026-06-15T19:07:03.411931Z","end":"2026-06-15T19:07:03.648072Z","steps":["trace[219333622] 'read index received' (duration: 232.268483ms)","trace[219333622] 'applied index is now lower than readState.Index' (duration: 3.872402ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:07:03.648215Z","caller":"traceutil/trace.go:171","msg":"trace[188984140] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"280.008019ms","start":"2026-06-15T19:07:03.368196Z","end":"2026-06-15T19:07:03.648204Z","steps":["trace[188984140] 'process raft request' (duration: 279.650291ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:03.648348Z","caller":"traceutil/trace.go:171","msg":"trace[696110672] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"207.880262ms","start":"2026-06-15T19:07:03.440435Z","end":"2026-06-15T19:07:03.648315Z","steps":["trace[696110672] 'process raft request' (duration: 207.498144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:07:03.648436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.446193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-15T19:07:03.64847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.962711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:07:03.648504Z","caller":"traceutil/trace.go:171","msg":"trace[442436878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"236.603885ms","start":"2026-06-15T19:07:03.411885Z","end":"2026-06-15T19:07:03.648488Z","steps":["trace[442436878] 'agreement among raft nodes before linearized reading' (duration: 236.35195ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:03.64853Z","caller":"traceutil/trace.go:171","msg":"trace[420658255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"200.120193ms","start":"2026-06-15T19:07:03.448389Z","end":"2026-06-15T19:07:03.648509Z","steps":["trace[420658255] 'agreement among raft nodes before linearized reading' (duration: 199.960241ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:09.623412Z","caller":"traceutil/trace.go:171","msg":"trace[455791744] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"125.978678ms","start":"2026-06-15T19:07:09.4974Z","end":"2026-06-15T19:07:09.623379Z","steps":["trace[455791744] 'process raft request' (duration: 125.386648ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:10.082687Z","caller":"traceutil/trace.go:171","msg":"trace[1266419843] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"330.535385ms","start":"2026-06-15T19:07:09.752125Z","end":"2026-06-15T19:07:10.08266Z","steps":["trace[1266419843] 'process raft request' (duration: 322.31369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:07:10.083358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:07:09.752112Z","time spent":"330.644868ms","remote":"127.0.0.1:34424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:07:10.888259Z","caller":"traceutil/trace.go:171","msg":"trace[724761082] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"764.465493ms","start":"2026-06-15T19:07:10.123755Z","end":"2026-06-15T19:07:10.888221Z","steps":["trace[724761082] 'process raft request' (duration: 710.227734ms)","trace[724761082] 'compare' (duration: 53.80779ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:07:10.88857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:07:10.123745Z","time spent":"764.618275ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:07:10.888918Z","caller":"traceutil/trace.go:171","msg":"trace[668291363] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"725.954872ms","start":"2026-06-15T19:07:10.162919Z","end":"2026-06-15T19:07:10.888874Z","steps":["trace[668291363] 'process raft request' (duration: 725.32966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:07:10.8892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:07:10.162867Z","time spent":"726.154146ms","remote":"127.0.0.1:34312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:07:10.889296Z","caller":"traceutil/trace.go:171","msg":"trace[809175742] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"391.122134ms","start":"2026-06-15T19:07:10.498153Z","end":"2026-06-15T19:07:10.889275Z","steps":["trace[809175742] 'process raft request' (duration: 390.646425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:07:10.889396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:07:10.498117Z","time spent":"391.224226ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:07:10.889218Z","caller":"traceutil/trace.go:171","msg":"trace[1967675662] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:613; }","duration":"183.008298ms","start":"2026-06-15T19:07:10.706183Z","end":"2026-06-15T19:07:10.889191Z","steps":["trace[1967675662] 'read index received' (duration: 127.968775ms)","trace[1967675662] 'applied index is now lower than readState.Index' (duration: 55.037293ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:07:10.889712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.52353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-15T19:07:10.889804Z","caller":"traceutil/trace.go:171","msg":"trace[1963711870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"183.641393ms","start":"2026-06-15T19:07:10.706112Z","end":"2026-06-15T19:07:10.889753Z","steps":["trace[1963711870] 'agreement among raft nodes before linearized reading' (duration: 183.426618ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:35.706031Z","caller":"traceutil/trace.go:171","msg":"trace[1479704219] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"255.897821ms","start":"2026-06-15T19:07:35.45011Z","end":"2026-06-15T19:07:35.706008Z","steps":["trace[1479704219] 'read index received' (duration: 255.82121ms)","trace[1479704219] 'applied index is now lower than readState.Index' (duration: 75.691µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:07:35.706166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.018183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:07:35.706198Z","caller":"traceutil/trace.go:171","msg":"trace[403288676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"256.108346ms","start":"2026-06-15T19:07:35.450078Z","end":"2026-06-15T19:07:35.706186Z","steps":["trace[403288676] 'agreement among raft nodes before linearized reading' (duration: 256.023294ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:07:35.706359Z","caller":"traceutil/trace.go:171","msg":"trace[155038678] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"364.49473ms","start":"2026-06-15T19:07:35.341836Z","end":"2026-06-15T19:07:35.706331Z","steps":["trace[155038678] 'process raft request' (duration: 364.034532ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:07:35.706531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:07:35.341813Z","time spent":"364.624532ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:04.436003Z","caller":"traceutil/trace.go:171","msg":"trace[1832439941] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"182.383546ms","start":"2026-06-15T19:08:04.253589Z","end":"2026-06-15T19:08:04.435973Z","steps":["trace[1832439941] 'process raft request' (duration: 166.154481ms)","trace[1832439941] 'compare' (duration: 16.080582ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:10.142847Z","caller":"traceutil/trace.go:171","msg":"trace[1990457677] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1031; }","duration":"129.831117ms","start":"2026-06-15T19:08:10.012979Z","end":"2026-06-15T19:08:10.14281Z","steps":["trace[1990457677] 'read index received' (duration: 129.706684ms)","trace[1990457677] 'applied index is now lower than readState.Index' (duration: 123.043µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:10.142945Z","caller":"traceutil/trace.go:171","msg":"trace[750581680] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"279.594826ms","start":"2026-06-15T19:08:09.863311Z","end":"2026-06-15T19:08:10.142906Z","steps":["trace[750581680] 'process raft request' (duration: 279.322552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:10.143058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.056321ms","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-06-15T19:08:10.143094Z","caller":"traceutil/trace.go:171","msg":"trace[1245166618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:997; }","duration":"130.138392ms","start":"2026-06-15T19:08:10.012943Z","end":"2026-06-15T19:08:10.143082Z","steps":["trace[1245166618] 'agreement among raft nodes before linearized reading' (duration: 129.991509ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:10.71778Z","caller":"traceutil/trace.go:171","msg":"trace[2097965147] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"688.458921ms","start":"2026-06-15T19:08:10.029285Z","end":"2026-06-15T19:08:10.717744Z","steps":["trace[2097965147] 'process raft request' (duration: 610.243727ms)","trace[2097965147] 'compare' (duration: 78.00141ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:08:10.717947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:10.029255Z","time spent":"688.610243ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:10.978374Z","caller":"traceutil/trace.go:171","msg":"trace[1499579897] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"831.655428ms","start":"2026-06-15T19:08:10.146705Z","end":"2026-06-15T19:08:10.978361Z","steps":["trace[1499579897] 'process raft request' (duration: 831.429994ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:10.978367Z","caller":"traceutil/trace.go:171","msg":"trace[1715635149] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1032; }","duration":"505.266309ms","start":"2026-06-15T19:08:10.473067Z","end":"2026-06-15T19:08:10.978333Z","steps":["trace[1715635149] 'read index received' (duration: 167.082327ms)","trace[1715635149] 'applied index is now lower than readState.Index' (duration: 338.182722ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:08:10.978679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.588494ms","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-06-15T19:08:10.978732Z","caller":"traceutil/trace.go:171","msg":"trace[482547228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:999; }","duration":"505.688947ms","start":"2026-06-15T19:08:10.473028Z","end":"2026-06-15T19:08:10.978717Z","steps":["trace[482547228] 'agreement among raft nodes before linearized reading' (duration: 505.461903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:10.978791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:10.472995Z","time spent":"505.782409ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-15T19:08:10.979133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:10.14668Z","time spent":"831.742229ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:24.731509Z","caller":"traceutil/trace.go:171","msg":"trace[376655130] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"114.365785ms","start":"2026-06-15T19:08:24.617106Z","end":"2026-06-15T19:08:24.731472Z","steps":["trace[376655130] 'process raft request' (duration: 114.195822ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:33.400481Z","caller":"traceutil/trace.go:171","msg":"trace[2079637036] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1113; }","duration":"118.254107ms","start":"2026-06-15T19:08:33.282205Z","end":"2026-06-15T19:08:33.400459Z","steps":["trace[2079637036] 'read index received' (duration: 118.029543ms)","trace[2079637036] 'applied index is now lower than readState.Index' (duration: 223.234µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:33.400648Z","caller":"traceutil/trace.go:171","msg":"trace[1416324102] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"269.243232ms","start":"2026-06-15T19:08:33.131392Z","end":"2026-06-15T19:08:33.400636Z","steps":["trace[1416324102] 'process raft request' (duration: 268.901606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:33.400852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.619954ms","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-06-15T19:08:33.40137Z","caller":"traceutil/trace.go:171","msg":"trace[2105666847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1074; }","duration":"119.188055ms","start":"2026-06-15T19:08:33.282159Z","end":"2026-06-15T19:08:33.401347Z","steps":["trace[2105666847] 'agreement among raft nodes before linearized reading' (duration: 118.499181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:33.401004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.775748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T19:08:33.401641Z","caller":"traceutil/trace.go:171","msg":"trace[853901712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"119.387329ms","start":"2026-06-15T19:08:33.282159Z","end":"2026-06-15T19:08:33.401547Z","steps":["trace[853901712] 'agreement among raft nodes before linearized reading' (duration: 118.565653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:34.480361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.857207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:08:34.480547Z","caller":"traceutil/trace.go:171","msg":"trace[967632411] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1114; }","duration":"1.070484407s","start":"2026-06-15T19:08:33.410046Z","end":"2026-06-15T19:08:34.480531Z","steps":["trace[967632411] 'read index received' (duration: 122.141841ms)","trace[967632411] 'applied index is now lower than readState.Index' (duration: 948.341446ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:34.48063Z","caller":"traceutil/trace.go:171","msg":"trace[436051898] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"1.074905881s","start":"2026-06-15T19:08:33.405713Z","end":"2026-06-15T19:08:34.480618Z","steps":["trace[436051898] 'process raft request' (duration: 126.484023ms)","trace[436051898] 'compare' (duration: 947.211875ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:08:34.48069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:33.405689Z","time spent":"1.074970932s","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:08:34.481123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.617512ms","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-06-15T19:08:34.481171Z","caller":"traceutil/trace.go:171","msg":"trace[1307215863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1076; }","duration":"250.705213ms","start":"2026-06-15T19:08:34.230453Z","end":"2026-06-15T19:08:34.481159Z","steps":["trace[1307215863] 'agreement among raft nodes before linearized reading' (duration: 250.518539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:34.481372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.719365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:08:34.481451Z","caller":"traceutil/trace.go:171","msg":"trace[2129786222] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1076; }","duration":"213.803687ms","start":"2026-06-15T19:08:34.267623Z","end":"2026-06-15T19:08:34.481426Z","steps":["trace[2129786222] 'agreement among raft nodes before linearized reading' (duration: 213.660984ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:34.481512Z","caller":"traceutil/trace.go:171","msg":"trace[351798533] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"1.074945511s","start":"2026-06-15T19:08:33.406362Z","end":"2026-06-15T19:08:34.481307Z","steps":["trace[351798533] 'process raft request' (duration: 1.074100486s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:34.48145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071355664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:08:34.481771Z","caller":"traceutil/trace.go:171","msg":"trace[171859500] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1076; }","duration":"1.071716182s","start":"2026-06-15T19:08:33.410042Z","end":"2026-06-15T19:08:34.481758Z","steps":["trace[171859500] 'agreement among raft nodes before linearized reading' (duration: 1.071304503s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:34.481689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:33.406342Z","time spent":"1.075285428s","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:08:34.481838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:33.410012Z","time spent":"1.071813953s","remote":"127.0.0.1:36766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:08:34.834388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.399565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:08:34.834614Z","caller":"traceutil/trace.go:171","msg":"trace[1130597259] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"348.361704ms","start":"2026-06-15T19:08:34.486224Z","end":"2026-06-15T19:08:34.834585Z","steps":["trace[1130597259] 'process raft request' (duration: 224.655413ms)","trace[1130597259] 'compare' (duration: 123.16171ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:08:34.834741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:34.486201Z","time spent":"348.493156ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:34.918502Z","caller":"traceutil/trace.go:171","msg":"trace[688941469] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"132.529926ms","start":"2026-06-15T19:08:34.785944Z","end":"2026-06-15T19:08:34.918474Z","steps":["trace[688941469] 'process raft request' (duration: 132.324693ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:37.254264Z","caller":"traceutil/trace.go:171","msg":"trace[1631708539] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"408.245582ms","start":"2026-06-15T19:08:36.845989Z","end":"2026-06-15T19:08:37.254234Z","steps":["trace[1631708539] 'process raft request' (duration: 408.073669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:37.254622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:36.845956Z","time spent":"408.391835ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:37.322604Z","caller":"traceutil/trace.go:171","msg":"trace[47567251] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1124; }","duration":"119.261927ms","start":"2026-06-15T19:08:37.203305Z","end":"2026-06-15T19:08:37.322567Z","steps":["trace[47567251] 'read index received' (duration: 50.86676ms)","trace[47567251] 'applied index is now lower than readState.Index' (duration: 68.394197ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:37.322735Z","caller":"traceutil/trace.go:171","msg":"trace[1809259156] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"397.948308ms","start":"2026-06-15T19:08:36.924775Z","end":"2026-06-15T19:08:37.322723Z","steps":["trace[1809259156] 'process raft request' (duration: 397.667392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:37.32287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:36.924735Z","time spent":"398.02678ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:08:37.32321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.873149ms","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-06-15T19:08:37.323295Z","caller":"traceutil/trace.go:171","msg":"trace[261910546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1085; }","duration":"120.019952ms","start":"2026-06-15T19:08:37.203253Z","end":"2026-06-15T19:08:37.323273Z","steps":["trace[261910546] 'agreement among raft nodes before linearized reading' (duration: 119.651505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:38.210146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.317767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:08:38.210265Z","caller":"traceutil/trace.go:171","msg":"trace[162010674] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"883.269169ms","start":"2026-06-15T19:08:37.326975Z","end":"2026-06-15T19:08:38.210244Z","steps":["trace[162010674] 'process raft request' (duration: 548.730308ms)","trace[162010674] 'compare' (duration: 333.673666ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:08:38.210331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:37.326953Z","time spent":"883.345071ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:08:38.213468Z","caller":"traceutil/trace.go:171","msg":"trace[2061088298] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1126; }","duration":"764.3326ms","start":"2026-06-15T19:08:37.449106Z","end":"2026-06-15T19:08:38.213439Z","steps":["trace[2061088298] 'read index received' (duration: 426.573536ms)","trace[2061088298] 'applied index is now lower than readState.Index' (duration: 337.757224ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:38.213608Z","caller":"traceutil/trace.go:171","msg":"trace[990868727] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"886.580282ms","start":"2026-06-15T19:08:37.327011Z","end":"2026-06-15T19:08:38.213591Z","steps":["trace[990868727] 'process raft request' (duration: 886.253766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:38.213818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:37.326993Z","time spent":"886.641494ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:08:38.213826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.13296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T19:08:38.213894Z","caller":"traceutil/trace.go:171","msg":"trace[479150110] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1087; }","duration":"623.254302ms","start":"2026-06-15T19:08:37.590623Z","end":"2026-06-15T19:08:38.213877Z","steps":["trace[479150110] 'agreement among raft nodes before linearized reading' (duration: 623.114529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:38.213938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:37.590597Z","time spent":"623.332873ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:08:38.21381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.697457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:08:38.215674Z","caller":"traceutil/trace.go:171","msg":"trace[2139073354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"766.634003ms","start":"2026-06-15T19:08:37.44902Z","end":"2026-06-15T19:08:38.215654Z","steps":["trace[2139073354] 'agreement among raft nodes before linearized reading' (duration: 764.727727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:38.215952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:37.449001Z","time spent":"766.873297ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T19:08:52.427319Z","caller":"traceutil/trace.go:171","msg":"trace[1818574916] linearizableReadLoop","detail":"{readStateIndex:1229; appliedIndex:1228; }","duration":"134.458731ms","start":"2026-06-15T19:08:52.292838Z","end":"2026-06-15T19:08:52.427296Z","steps":["trace[1818574916] 'read index received' (duration: 134.308087ms)","trace[1818574916] 'applied index is now lower than readState.Index' (duration: 149.504µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:08:52.42737Z","caller":"traceutil/trace.go:171","msg":"trace[1471268287] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"142.656075ms","start":"2026-06-15T19:08:52.284684Z","end":"2026-06-15T19:08:52.42734Z","steps":["trace[1471268287] 'process raft request' (duration: 142.47214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:52.427496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.644865ms","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-06-15T19:08:52.427527Z","caller":"traceutil/trace.go:171","msg":"trace[1874688220] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1183; }","duration":"134.748977ms","start":"2026-06-15T19:08:52.292768Z","end":"2026-06-15T19:08:52.427517Z","steps":["trace[1874688220] 'agreement among raft nodes before linearized reading' (duration: 134.616164ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:53.59082Z","caller":"traceutil/trace.go:171","msg":"trace[1890033085] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"261.731638ms","start":"2026-06-15T19:08:53.329062Z","end":"2026-06-15T19:08:53.590794Z","steps":["trace[1890033085] 'process raft request' (duration: 261.531733ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:08:54.235523Z","caller":"traceutil/trace.go:171","msg":"trace[1260602550] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"859.306951ms","start":"2026-06-15T19:08:53.376184Z","end":"2026-06-15T19:08:54.235491Z","steps":["trace[1260602550] 'process raft request' (duration: 856.932603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:08:54.235675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:08:53.376146Z","time spent":"859.456884ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:09:06.966785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.614854ms","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-06-15T19:09:06.966945Z","caller":"traceutil/trace.go:171","msg":"trace[1172286808] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1233; }","duration":"132.865208ms","start":"2026-06-15T19:09:06.834054Z","end":"2026-06-15T19:09:06.966919Z","steps":["trace[1172286808] 'count revisions from in-memory index tree' (duration: 132.483702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:10.86138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.888124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:09:10.861441Z","caller":"traceutil/trace.go:171","msg":"trace[804427875] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1248; }","duration":"672.994676ms","start":"2026-06-15T19:09:10.188431Z","end":"2026-06-15T19:09:10.861426Z","steps":["trace[804427875] 'count revisions from in-memory index tree' (duration: 672.806622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:10.861472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:10.188413Z","time spent":"673.050668ms","remote":"127.0.0.1:52882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:09:10.862508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.727609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:09:10.862584Z","caller":"traceutil/trace.go:171","msg":"trace[595420453] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"354.088119ms","start":"2026-06-15T19:09:10.508484Z","end":"2026-06-15T19:09:10.862572Z","steps":["trace[595420453] 'read index received' (duration: 132.477712ms)","trace[595420453] 'applied index is now lower than readState.Index' (duration: 221.609317ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:09:10.862697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.209251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-15T19:09:10.862726Z","caller":"traceutil/trace.go:171","msg":"trace[1728118752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1249; }","duration":"354.278643ms","start":"2026-06-15T19:09:10.508439Z","end":"2026-06-15T19:09:10.862718Z","steps":["trace[1728118752] 'agreement among raft nodes before linearized reading' (duration: 354.181401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:10.862741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.15995ms","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-06-15T19:09:10.862746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:10.508425Z","time spent":"354.317253ms","remote":"127.0.0.1:34510","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-06-15T19:09:10.862763Z","caller":"traceutil/trace.go:171","msg":"trace[425644448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1249; }","duration":"348.192701ms","start":"2026-06-15T19:09:10.514564Z","end":"2026-06-15T19:09:10.862757Z","steps":["trace[425644448] 'agreement among raft nodes before linearized reading' (duration: 348.078778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:10.862792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:10.514559Z","time spent":"348.223391ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-15T19:09:10.862647Z","caller":"traceutil/trace.go:171","msg":"trace[450096769] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"469.285645ms","start":"2026-06-15T19:09:10.393322Z","end":"2026-06-15T19:09:10.862607Z","steps":["trace[450096769] 'process raft request' (duration: 248.391912ms)","trace[450096769] 'compare' (duration: 218.903134ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:09:10.862927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.122231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-15T19:09:10.862925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.4312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-15T19:09:10.862958Z","caller":"traceutil/trace.go:171","msg":"trace[1456349676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1249; }","duration":"175.157292ms","start":"2026-06-15T19:09:10.687794Z","end":"2026-06-15T19:09:10.862952Z","steps":["trace[1456349676] 'agreement among raft nodes before linearized reading' (duration: 175.089891ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:10.862988Z","caller":"traceutil/trace.go:171","msg":"trace[124793405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1249; }","duration":"120.549263ms","start":"2026-06-15T19:09:10.742422Z","end":"2026-06-15T19:09:10.862971Z","steps":["trace[124793405] 'agreement among raft nodes before linearized reading' (duration: 120.330398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:10.862962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:10.393297Z","time spent":"469.560862ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:09:11.040334Z","caller":"traceutil/trace.go:171","msg":"trace[477346279] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"172.903777ms","start":"2026-06-15T19:09:10.867409Z","end":"2026-06-15T19:09:11.040313Z","steps":["trace[477346279] 'process raft request' (duration: 122.023383ms)","trace[477346279] 'compare' (duration: 50.767861ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:09:11.090008Z","caller":"traceutil/trace.go:171","msg":"trace[772335617] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1298; }","duration":"152.704944ms","start":"2026-06-15T19:09:10.93728Z","end":"2026-06-15T19:09:11.089985Z","steps":["trace[772335617] 'read index received' (duration: 52.17683ms)","trace[772335617] 'applied index is now lower than readState.Index' (duration: 100.526904ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:09:11.09019Z","caller":"traceutil/trace.go:171","msg":"trace[730206508] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"222.309082ms","start":"2026-06-15T19:09:10.867845Z","end":"2026-06-15T19:09:11.090154Z","steps":["trace[730206508] 'process raft request' (duration: 221.988635ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:11.090255Z","caller":"traceutil/trace.go:171","msg":"trace[1763198740] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"222.255691ms","start":"2026-06-15T19:09:10.867962Z","end":"2026-06-15T19:09:11.090218Z","steps":["trace[1763198740] 'process raft request' (duration: 221.950275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:11.090344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.047511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.249\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-15T19:09:11.090396Z","caller":"traceutil/trace.go:171","msg":"trace[1283671570] range","detail":"{range_begin:/registry/masterleases/199.19.213.249; range_end:; response_count:1; response_revision:1253; }","duration":"153.137752ms","start":"2026-06-15T19:09:10.937246Z","end":"2026-06-15T19:09:11.090383Z","steps":["trace[1283671570] 'agreement among raft nodes before linearized reading' (duration: 152.809276ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:11.090759Z","caller":"traceutil/trace.go:171","msg":"trace[612178876] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"222.73046ms","start":"2026-06-15T19:09:10.868003Z","end":"2026-06-15T19:09:11.090733Z","steps":["trace[612178876] 'process raft request' (duration: 221.942685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:12.081027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.596076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:09:12.081297Z","caller":"traceutil/trace.go:171","msg":"trace[292437514] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"443.177675ms","start":"2026-06-15T19:09:11.638097Z","end":"2026-06-15T19:09:12.081274Z","steps":["trace[292437514] 'process raft request' (duration: 37.272653ms)","trace[292437514] 'compare' (duration: 405.30583ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:09:12.08136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:11.63808Z","time spent":"443.250257ms","remote":"127.0.0.1:34280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T19:09:12.199264Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363760875397018883,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T19:09:12.450931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.76352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:09:12.451119Z","caller":"traceutil/trace.go:171","msg":"trace[1346522499] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1309; }","duration":"752.688785ms","start":"2026-06-15T19:09:11.698414Z","end":"2026-06-15T19:09:12.451102Z","steps":["trace[1346522499] 'read index received' (duration: 532.613118ms)","trace[1346522499] 'applied index is now lower than readState.Index' (duration: 220.074327ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:09:12.451171Z","caller":"traceutil/trace.go:171","msg":"trace[509228758] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"799.682362ms","start":"2026-06-15T19:09:11.65145Z","end":"2026-06-15T19:09:12.451132Z","steps":["trace[509228758] 'process raft request' (duration: 799.58525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:12.451309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.882279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-15T19:09:12.451325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:11.651407Z","time spent":"799.840984ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:09:12.451371Z","caller":"traceutil/trace.go:171","msg":"trace[901988028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1261; }","duration":"752.959331ms","start":"2026-06-15T19:09:11.698385Z","end":"2026-06-15T19:09:12.451345Z","steps":["trace[901988028] 'agreement among raft nodes before linearized reading' (duration: 752.809218ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:12.451173Z","caller":"traceutil/trace.go:171","msg":"trace[1958218085] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"800.000447ms","start":"2026-06-15T19:09:11.651147Z","end":"2026-06-15T19:09:12.451148Z","steps":["trace[1958218085] 'process raft request' (duration: 579.941611ms)","trace[1958218085] 'compare' (duration: 219.622948ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:09:12.451417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:11.698373Z","time spent":"753.035162ms","remote":"127.0.0.1:34510","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-06-15T19:09:12.451489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:11.651127Z","time spent":"800.293734ms","remote":"127.0.0.1:34280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-15T19:09:12.974086Z","caller":"traceutil/trace.go:171","msg":"trace[1343778704] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"105.286339ms","start":"2026-06-15T19:09:12.868769Z","end":"2026-06-15T19:09:12.974056Z","steps":["trace[1343778704] 'process raft request' (duration: 60.853734ms)","trace[1343778704] 'compare' (duration: 44.098529ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:09:14.726906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.861917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:09:14.727005Z","caller":"traceutil/trace.go:171","msg":"trace[1673352694] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"202.18217ms","start":"2026-06-15T19:09:14.524809Z","end":"2026-06-15T19:09:14.726991Z","steps":["trace[1673352694] 'process raft request' (duration: 49.176961ms)","trace[1673352694] 'compare' (duration: 152.775125ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:09:23.584289Z","caller":"traceutil/trace.go:171","msg":"trace[45962700] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1396; }","duration":"485.755014ms","start":"2026-06-15T19:09:23.098512Z","end":"2026-06-15T19:09:23.584267Z","steps":["trace[45962700] 'read index received' (duration: 485.573551ms)","trace[45962700] 'applied index is now lower than readState.Index' (duration: 180.673µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:09:23.584369Z","caller":"traceutil/trace.go:171","msg":"trace[1429412019] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"579.219796ms","start":"2026-06-15T19:09:23.005118Z","end":"2026-06-15T19:09:23.584338Z","steps":["trace[1429412019] 'process raft request' (duration: 579.018013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:23.584502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.464411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:09:23.584536Z","caller":"traceutil/trace.go:171","msg":"trace[605561924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1345; }","duration":"136.541493ms","start":"2026-06-15T19:09:23.447984Z","end":"2026-06-15T19:09:23.584526Z","steps":["trace[605561924] 'agreement among raft nodes before linearized reading' (duration: 136.475552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:23.584533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:23.005092Z","time spent":"579.350009ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:09:23.584521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.981509ms","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-06-15T19:09:23.584599Z","caller":"traceutil/trace.go:171","msg":"trace[226490254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1345; }","duration":"486.102191ms","start":"2026-06-15T19:09:23.098477Z","end":"2026-06-15T19:09:23.584579Z","steps":["trace[226490254] 'agreement among raft nodes before linearized reading' (duration: 485.877996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:23.58464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:23.098464Z","time spent":"486.167843ms","remote":"127.0.0.1:34510","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-06-15T19:09:23.584708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.197002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-15T19:09:23.584803Z","caller":"traceutil/trace.go:171","msg":"trace[1542752059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1345; }","duration":"348.328044ms","start":"2026-06-15T19:09:23.236452Z","end":"2026-06-15T19:09:23.58478Z","steps":["trace[1542752059] 'agreement among raft nodes before linearized reading' (duration: 348.10488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:23.584858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:23.236438Z","time spent":"348.405155ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-15T19:09:23.584853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.996803ms","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-06-15T19:09:23.584924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.359465ms","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-06-15T19:09:23.585005Z","caller":"traceutil/trace.go:171","msg":"trace[1489753767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1345; }","duration":"342.676492ms","start":"2026-06-15T19:09:23.24231Z","end":"2026-06-15T19:09:23.584987Z","steps":["trace[1489753767] 'agreement among raft nodes before linearized reading' (duration: 342.188793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:23.585005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.097555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-06-15T19:09:23.585137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:23.2423Z","time spent":"342.819945ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-15T19:09:23.585169Z","caller":"traceutil/trace.go:171","msg":"trace[545717562] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1345; }","duration":"112.35582ms","start":"2026-06-15T19:09:23.472782Z","end":"2026-06-15T19:09:23.585137Z","steps":["trace[545717562] 'agreement among raft nodes before linearized reading' (duration: 111.82249ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:23.584938Z","caller":"traceutil/trace.go:171","msg":"trace[1700471647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1345; }","duration":"417.097225ms","start":"2026-06-15T19:09:23.167825Z","end":"2026-06-15T19:09:23.584922Z","steps":["trace[1700471647] 'agreement among raft nodes before linearized reading' (duration: 416.592624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:23.585397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:23.167814Z","time spent":"417.570344ms","remote":"127.0.0.1:34510","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-06-15T19:09:24.632072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.560109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T19:09:24.632158Z","caller":"traceutil/trace.go:171","msg":"trace[1031231652] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1350; }","duration":"367.687891ms","start":"2026-06-15T19:09:24.264447Z","end":"2026-06-15T19:09:24.632135Z","steps":["trace[1031231652] 'count revisions from in-memory index tree' (duration: 367.464306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:24.632207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:24.264433Z","time spent":"367.762342ms","remote":"127.0.0.1:34720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:09:24.632234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.861415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:09:24.632305Z","caller":"traceutil/trace.go:171","msg":"trace[449998500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"183.981739ms","start":"2026-06-15T19:09:24.448303Z","end":"2026-06-15T19:09:24.632285Z","steps":["trace[449998500] 'range keys from in-memory index tree' (duration: 183.775664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:24.632642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.247848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-15T19:09:24.632694Z","caller":"traceutil/trace.go:171","msg":"trace[501095680] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1350; }","duration":"416.33819ms","start":"2026-06-15T19:09:24.216341Z","end":"2026-06-15T19:09:24.63268Z","steps":["trace[501095680] 'range keys from in-memory index tree' (duration: 415.358501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:09:24.632731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:09:24.216319Z","time spent":"416.402121ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-06-15T19:09:24.752435Z","caller":"traceutil/trace.go:171","msg":"trace[1984858096] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"116.454571ms","start":"2026-06-15T19:09:24.635955Z","end":"2026-06-15T19:09:24.75241Z","steps":["trace[1984858096] 'process raft request' (duration: 116.318579ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:09:24.756796Z","caller":"traceutil/trace.go:171","msg":"trace[1787015798] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"119.958711ms","start":"2026-06-15T19:09:24.636822Z","end":"2026-06-15T19:09:24.756781Z","steps":["trace[1787015798] 'process raft request' (duration: 119.88241ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:13:11.0212Z","caller":"traceutil/trace.go:171","msg":"trace[193736523] linearizableReadLoop","detail":"{readStateIndex:2517; appliedIndex:2513; }","duration":"147.052964ms","start":"2026-06-15T19:13:10.874114Z","end":"2026-06-15T19:13:11.021167Z","steps":["trace[193736523] 'read index received' (duration: 9.316705ms)","trace[193736523] 'applied index is now lower than readState.Index' (duration: 137.734459ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:13:11.021348Z","caller":"traceutil/trace.go:171","msg":"trace[528526705] transaction","detail":"{read_only:false; response_revision:2418; number_of_response:1; }","duration":"155.223007ms","start":"2026-06-15T19:13:10.866098Z","end":"2026-06-15T19:13:11.021321Z","steps":["trace[528526705] 'process raft request' (duration: 154.940741ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:13:11.021406Z","caller":"traceutil/trace.go:171","msg":"trace[641553678] transaction","detail":"{read_only:false; response_revision:2419; number_of_response:1; }","duration":"155.154816ms","start":"2026-06-15T19:13:10.86621Z","end":"2026-06-15T19:13:11.021365Z","steps":["trace[641553678] 'process raft request' (duration: 154.909561ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:13:11.021291Z","caller":"traceutil/trace.go:171","msg":"trace[2009931448] transaction","detail":"{read_only:false; response_revision:2417; number_of_response:1; }","duration":"156.218557ms","start":"2026-06-15T19:13:10.865019Z","end":"2026-06-15T19:13:11.021238Z","steps":["trace[2009931448] 'process raft request' (duration: 121.201499ms)","trace[2009931448] 'compare' (duration: 34.675301ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:13:11.021848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.500722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:13:11.021909Z","caller":"traceutil/trace.go:171","msg":"trace[2054450484] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2419; }","duration":"147.833839ms","start":"2026-06-15T19:13:10.87406Z","end":"2026-06-15T19:13:11.021894Z","steps":["trace[2054450484] 'agreement among raft nodes before linearized reading' (duration: 147.287748ms)"],"step_count":1}