{"level":"warn","ts":"2026-07-17T09:08:40.197894Z","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-17T09:08:40.198033Z","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-17T09:08:40.198137Z","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-17T09:08:40.198154Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-17T09:08:40.198179Z","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-17T09:08:40.199138Z","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-17T09:08:40.199239Z","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-17T09:08:40.201971Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.446746ms"} {"level":"info","ts":"2026-07-17T09:08:40.207969Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-07-17T09:08:40.208089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T09:08:40.208113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-07-17T09:08:40.208126Z","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-17T09:08:40.208138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-07-17T09:08:40.208176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-07-17T09:08:40.214876Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T09:08:40.216227Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T09:08:40.217227Z","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-17T09:08:40.219368Z","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-17T09:08:40.219566Z","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-17T09:08:40.219857Z","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-17T09:08:40.220029Z","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-17T09:08:40.220063Z","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-17T09:08:40.220287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-07-17T09:08:40.220398Z","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-17T09:08:40.231799Z","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-17T09:08:40.232095Z","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-17T09:08:40.232135Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T09:08:40.232219Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-17T09:08:40.232235Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-17T09:08:41.208431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T09:08:41.208482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T09:08:41.208509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-07-17T09:08:41.208523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-07-17T09:08:41.208531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-17T09:08:41.208545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-07-17T09:08:41.208555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-17T09:08:41.209151Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T09:08:41.209911Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T09:08:41.209912Z","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-17T09:08:41.209965Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T09:08:41.211032Z","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-17T09:08:41.211153Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T09:08:41.211165Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T09:08:41.211192Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T09:08:41.21119Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T09:08:41.21555Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T09:08:41.217917Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-07-17T09:08:50.185274Z","caller":"traceutil/trace.go:171","msg":"trace[1516272486] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"124.12125ms","start":"2026-07-17T09:08:50.061128Z","end":"2026-07-17T09:08:50.18525Z","steps":["trace[1516272486] 'process raft request' (duration: 123.972796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:51.077412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.283685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-17T09:08:51.077548Z","caller":"traceutil/trace.go:171","msg":"trace[734321223] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:353; }","duration":"159.48052ms","start":"2026-07-17T09:08:50.918045Z","end":"2026-07-17T09:08:51.077526Z","steps":["trace[734321223] 'range keys from in-memory index tree' (duration: 159.203213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:51.077499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.570029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-07-17T09:08:51.07814Z","caller":"traceutil/trace.go:171","msg":"trace[97064744] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:353; }","duration":"117.249207ms","start":"2026-07-17T09:08:50.960873Z","end":"2026-07-17T09:08:51.078122Z","steps":["trace[97064744] 'range keys from in-memory index tree' (duration: 116.490056ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:08:51.470237Z","caller":"traceutil/trace.go:171","msg":"trace[1849443335] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"381.718947ms","start":"2026-07-17T09:08:51.088487Z","end":"2026-07-17T09:08:51.470206Z","steps":["trace[1849443335] 'process raft request' (duration: 380.666239ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:08:51.470412Z","caller":"traceutil/trace.go:171","msg":"trace[1278153642] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"381.908122ms","start":"2026-07-17T09:08:51.088487Z","end":"2026-07-17T09:08:51.470396Z","steps":["trace[1278153642] 'process raft request' (duration: 381.661696ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:08:51.470597Z","caller":"traceutil/trace.go:171","msg":"trace[183147132] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"382.109127ms","start":"2026-07-17T09:08:51.08848Z","end":"2026-07-17T09:08:51.470589Z","steps":["trace[183147132] 'process raft request' (duration: 381.717887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:51.470837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:51.088477Z","time spent":"381.83155ms","remote":"127.0.0.1:56410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T09:08:51.471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:51.088465Z","time spent":"382.152979ms","remote":"127.0.0.1:56138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T09:08:51.471045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:51.088478Z","time spent":"381.968093ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T09:08:51.476875Z","caller":"traceutil/trace.go:171","msg":"trace[1045816527] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:363; }","duration":"365.869415ms","start":"2026-07-17T09:08:51.110978Z","end":"2026-07-17T09:08:51.476847Z","steps":["trace[1045816527] 'read index received' (duration: 83.832418ms)","trace[1045816527] 'applied index is now lower than readState.Index' (duration: 282.036077ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T09:08:51.477019Z","caller":"traceutil/trace.go:171","msg":"trace[1486000800] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"388.223011ms","start":"2026-07-17T09:08:51.088775Z","end":"2026-07-17T09:08:51.476998Z","steps":["trace[1486000800] 'process raft request' (duration: 388.032255ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:08:51.477075Z","caller":"traceutil/trace.go:171","msg":"trace[1019830751] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"388.381255ms","start":"2026-07-17T09:08:51.088672Z","end":"2026-07-17T09:08:51.477054Z","steps":["trace[1019830751] 'process raft request' (duration: 381.653636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:51.477326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.414916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-17T09:08:51.477433Z","caller":"traceutil/trace.go:171","msg":"trace[173053983] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:360; }","duration":"265.568879ms","start":"2026-07-17T09:08:51.211841Z","end":"2026-07-17T09:08:51.47741Z","steps":["trace[173053983] 'agreement among raft nodes before linearized reading' (duration: 265.338744ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:08:51.477144Z","caller":"traceutil/trace.go:171","msg":"trace[995111447] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"388.402885ms","start":"2026-07-17T09:08:51.088734Z","end":"2026-07-17T09:08:51.477137Z","steps":["trace[995111447] 'process raft request' (duration: 388.024475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:51.477476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:51.088666Z","time spent":"388.750074ms","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T09:08:51.477203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.204024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-17T09:08:51.47761Z","caller":"traceutil/trace.go:171","msg":"trace[641073952] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:360; }","duration":"366.628295ms","start":"2026-07-17T09:08:51.110971Z","end":"2026-07-17T09:08:51.477599Z","steps":["trace[641073952] 'agreement among raft nodes before linearized reading' (duration: 366.156503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:51.477668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:51.110931Z","time spent":"366.708838ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-07-17T09:08:51.477664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:51.088728Z","time spent":"388.836976ms","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T09:08:51.47781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:51.088769Z","time spent":"388.484297ms","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T09:08:51.479023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.276551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-17T09:08:51.479095Z","caller":"traceutil/trace.go:171","msg":"trace[1132382161] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:360; }","duration":"318.395174ms","start":"2026-07-17T09:08:51.160689Z","end":"2026-07-17T09:08:51.479084Z","steps":["trace[1132382161] 'agreement among raft nodes before linearized reading' (duration: 316.35002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:51.479142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:51.160664Z","time spent":"318.470277ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-07-17T09:08:57.541509Z","caller":"traceutil/trace.go:171","msg":"trace[1816354022] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"324.321372ms","start":"2026-07-17T09:08:57.217162Z","end":"2026-07-17T09:08:57.541484Z","steps":["trace[1816354022] 'process raft request' (duration: 324.159048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:57.541743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:57.217149Z","time spent":"324.522356ms","remote":"127.0.0.1:56210","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-17T09:08:58.622685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.633809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T09:08:58.62283Z","caller":"traceutil/trace.go:171","msg":"trace[2025940600] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"865.510339ms","start":"2026-07-17T09:08:57.757303Z","end":"2026-07-17T09:08:58.622813Z","steps":["trace[2025940600] 'read index received' (duration: 291.491339ms)","trace[2025940600] 'applied index is now lower than readState.Index' (duration: 574.01715ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:08:58.62294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.634132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T09:08:58.622979Z","caller":"traceutil/trace.go:171","msg":"trace[1730153095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"865.702394ms","start":"2026-07-17T09:08:57.757268Z","end":"2026-07-17T09:08:58.62297Z","steps":["trace[1730153095] 'agreement among raft nodes before linearized reading' (duration: 865.633012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:58.623062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:57.75725Z","time spent":"865.803986ms","remote":"127.0.0.1:55974","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-17T09:08:58.622975Z","caller":"traceutil/trace.go:171","msg":"trace[686638983] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"1.045479093s","start":"2026-07-17T09:08:57.577422Z","end":"2026-07-17T09:08:58.622901Z","steps":["trace[686638983] 'process raft request' (duration: 471.354711ms)","trace[686638983] 'compare' (duration: 573.404793ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:08:58.623092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.365166ms","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-07-17T09:08:58.623156Z","caller":"traceutil/trace.go:171","msg":"trace[1204168197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"765.487909ms","start":"2026-07-17T09:08:57.857648Z","end":"2026-07-17T09:08:58.623136Z","steps":["trace[1204168197] 'agreement among raft nodes before linearized reading' (duration: 765.345966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:58.623165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.170185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T09:08:58.623187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.90324ms","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-07-17T09:08:58.623202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:57.857621Z","time spent":"765.572362ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T09:08:58.623212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:57.577404Z","time spent":"1.0457141s","remote":"127.0.0.1:56210","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-17T09:08:58.62324Z","caller":"traceutil/trace.go:171","msg":"trace[345499187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"649.004083ms","start":"2026-07-17T09:08:57.974221Z","end":"2026-07-17T09:08:58.623225Z","steps":["trace[345499187] 'agreement among raft nodes before linearized reading' (duration: 648.90465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:58.623275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:57.974197Z","time spent":"649.069655ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T09:08:58.623261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.936925ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T09:08:58.623312Z","caller":"traceutil/trace.go:171","msg":"trace[1718657531] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:412; }","duration":"547.990357ms","start":"2026-07-17T09:08:58.075317Z","end":"2026-07-17T09:08:58.623307Z","steps":["trace[1718657531] 'agreement among raft nodes before linearized reading' (duration: 547.931695ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:08:58.623232Z","caller":"traceutil/trace.go:171","msg":"trace[2099929830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"606.283587ms","start":"2026-07-17T09:08:58.016934Z","end":"2026-07-17T09:08:58.623218Z","steps":["trace[2099929830] 'agreement among raft nodes before linearized reading' (duration: 606.186735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:58.623372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:58.01691Z","time spent":"606.448772ms","remote":"127.0.0.1:55986","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-17T09:08:59.517426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394760216482718,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T09:08:59.553794Z","caller":"traceutil/trace.go:171","msg":"trace[54391462] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"925.936734ms","start":"2026-07-17T09:08:58.627803Z","end":"2026-07-17T09:08:59.553739Z","steps":["trace[54391462] 'process raft request' (duration: 925.605176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:59.553953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:58.627791Z","time spent":"926.081108ms","remote":"127.0.0.1:56210","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-17T09:08:59.805683Z","caller":"traceutil/trace.go:171","msg":"trace[102260402] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.177200185s","start":"2026-07-17T09:08:58.628459Z","end":"2026-07-17T09:08:59.80566Z","steps":["trace[102260402] 'process raft request' (duration: 1.176866846s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:59.805806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:58.628436Z","time spent":"1.177304387s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T09:08:59.806042Z","caller":"traceutil/trace.go:171","msg":"trace[1469313842] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.174092172s","start":"2026-07-17T09:08:58.631939Z","end":"2026-07-17T09:08:59.806031Z","steps":["trace[1469313842] 'process raft request' (duration: 1.173538607s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:59.806104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:58.63192Z","time spent":"1.174154804s","remote":"127.0.0.1:56138","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-17T09:08:59.810703Z","caller":"traceutil/trace.go:171","msg":"trace[642794648] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:423; }","duration":"793.925845ms","start":"2026-07-17T09:08:59.016766Z","end":"2026-07-17T09:08:59.810691Z","steps":["trace[642794648] 'read index received' (duration: 536.861071ms)","trace[642794648] 'applied index is now lower than readState.Index' (duration: 257.064074ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:08:59.810834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.062189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T09:08:59.81086Z","caller":"traceutil/trace.go:171","msg":"trace[46447988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"794.126592ms","start":"2026-07-17T09:08:59.016725Z","end":"2026-07-17T09:08:59.810852Z","steps":["trace[46447988] 'agreement among raft nodes before linearized reading' (duration: 794.07413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:08:59.810888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:08:59.016701Z","time spent":"794.182843ms","remote":"127.0.0.1:55974","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-17T09:08:59.810962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.336194ms","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-17T09:08:59.811001Z","caller":"traceutil/trace.go:171","msg":"trace[2069556474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"265.377735ms","start":"2026-07-17T09:08:59.545615Z","end":"2026-07-17T09:08:59.810993Z","steps":["trace[2069556474] 'agreement among raft nodes before linearized reading' (duration: 265.313993ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:21.219614Z","caller":"traceutil/trace.go:171","msg":"trace[134001561] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"483.023462ms","start":"2026-07-17T09:09:20.736562Z","end":"2026-07-17T09:09:21.219585Z","steps":["trace[134001561] 'process raft request' (duration: 482.391445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:21.219824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:20.736537Z","time spent":"483.178375ms","remote":"127.0.0.1:56210","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-17T09:09:21.22679Z","caller":"traceutil/trace.go:171","msg":"trace[1312555919] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"399.661205ms","start":"2026-07-17T09:09:20.827103Z","end":"2026-07-17T09:09:21.226764Z","steps":["trace[1312555919] 'process raft request' (duration: 399.525761ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:21.226785Z","caller":"traceutil/trace.go:171","msg":"trace[288548047] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"401.716249ms","start":"2026-07-17T09:09:20.825013Z","end":"2026-07-17T09:09:21.226729Z","steps":["trace[288548047] 'process raft request' (duration: 401.468223ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:21.226928Z","caller":"traceutil/trace.go:171","msg":"trace[276526065] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"385.026046ms","start":"2026-07-17T09:09:20.841893Z","end":"2026-07-17T09:09:21.226919Z","steps":["trace[276526065] 'process raft request' (duration: 384.84008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:21.226984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:20.841889Z","time spent":"385.062287ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T09:09:21.227072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:20.824993Z","time spent":"401.949026ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T09:09:21.227145Z","caller":"traceutil/trace.go:171","msg":"trace[1510474983] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"384.692517ms","start":"2026-07-17T09:09:20.842421Z","end":"2026-07-17T09:09:21.227113Z","steps":["trace[1510474983] 'process raft request' (duration: 384.371079ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:21.227216Z","caller":"traceutil/trace.go:171","msg":"trace[1705949084] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"394.464857ms","start":"2026-07-17T09:09:20.832737Z","end":"2026-07-17T09:09:21.227202Z","steps":["trace[1705949084] 'process raft request' (duration: 393.925423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:21.227266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:20.84241Z","time spent":"384.797949ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T09:09:21.227309Z","caller":"traceutil/trace.go:171","msg":"trace[1154556070] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"385.506729ms","start":"2026-07-17T09:09:20.841793Z","end":"2026-07-17T09:09:21.227299Z","steps":["trace[1154556070] 'process raft request' (duration: 384.907092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:21.22735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:20.841772Z","time spent":"385.557899ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T09:09:21.227395Z","caller":"traceutil/trace.go:171","msg":"trace[1459589849] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"385.354295ms","start":"2026-07-17T09:09:20.842032Z","end":"2026-07-17T09:09:21.227386Z","steps":["trace[1459589849] 'process raft request' (duration: 384.729309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:21.226884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:20.827069Z","time spent":"399.768838ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T09:09:21.227436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:20.841975Z","time spent":"385.440456ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T09:09:21.227266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:20.832706Z","time spent":"394.538818ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T09:09:21.227547Z","caller":"traceutil/trace.go:171","msg":"trace[548104185] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"365.153289ms","start":"2026-07-17T09:09:20.862386Z","end":"2026-07-17T09:09:21.22754Z","steps":["trace[548104185] 'process raft request' (duration: 364.499191ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:21.22753Z","caller":"traceutil/trace.go:171","msg":"trace[621080129] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:546; }","duration":"211.525145ms","start":"2026-07-17T09:09:21.015982Z","end":"2026-07-17T09:09:21.227508Z","steps":["trace[621080129] 'read index received' (duration: 203.228914ms)","trace[621080129] 'applied index is now lower than readState.Index' (duration: 8.295211ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:09:21.227579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:20.86235Z","time spent":"365.21397ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T09:09:21.227577Z","caller":"traceutil/trace.go:171","msg":"trace[1098996757] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"384.572735ms","start":"2026-07-17T09:09:20.842994Z","end":"2026-07-17T09:09:21.227566Z","steps":["trace[1098996757] 'process raft request' (duration: 383.826055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:21.227631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:20.842963Z","time spent":"384.643596ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T09:09:21.227638Z","caller":"traceutil/trace.go:171","msg":"trace[1187476179] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"372.569765ms","start":"2026-07-17T09:09:20.855062Z","end":"2026-07-17T09:09:21.227632Z","steps":["trace[1187476179] 'process raft request' (duration: 371.788284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:21.227666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.659958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T09:09:21.22768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:20.855027Z","time spent":"372.633067ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T09:09:21.227695Z","caller":"traceutil/trace.go:171","msg":"trace[968417437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"211.76149ms","start":"2026-07-17T09:09:21.015923Z","end":"2026-07-17T09:09:21.227685Z","steps":["trace[968417437] 'agreement among raft nodes before linearized reading' (duration: 211.665698ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:21.227713Z","caller":"traceutil/trace.go:171","msg":"trace[1746687451] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"325.812903ms","start":"2026-07-17T09:09:20.901888Z","end":"2026-07-17T09:09:21.227701Z","steps":["trace[1746687451] 'process raft request' (duration: 325.167065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:21.227763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:20.901855Z","time spent":"325.888835ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T09:09:21.227915Z","caller":"traceutil/trace.go:171","msg":"trace[145388600] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"228.969008ms","start":"2026-07-17T09:09:20.998929Z","end":"2026-07-17T09:09:21.227898Z","steps":["trace[145388600] 'process raft request' (duration: 228.493135ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:24.585227Z","caller":"traceutil/trace.go:171","msg":"trace[461216805] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"124.940894ms","start":"2026-07-17T09:09:24.460258Z","end":"2026-07-17T09:09:24.585199Z","steps":["trace[461216805] 'process raft request' (duration: 123.775923ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:25.704568Z","caller":"traceutil/trace.go:171","msg":"trace[1339975622] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"462.101321ms","start":"2026-07-17T09:09:25.242442Z","end":"2026-07-17T09:09:25.704543Z","steps":["trace[1339975622] 'read index received' (duration: 461.449694ms)","trace[1339975622] 'applied index is now lower than readState.Index' (duration: 650.617µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:09:25.704733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.275386ms","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-17T09:09:25.704759Z","caller":"traceutil/trace.go:171","msg":"trace[1610644457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"462.348648ms","start":"2026-07-17T09:09:25.242401Z","end":"2026-07-17T09:09:25.704749Z","steps":["trace[1610644457] 'agreement among raft nodes before linearized reading' (duration: 462.229535ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:25.704713Z","caller":"traceutil/trace.go:171","msg":"trace[541925411] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"613.778682ms","start":"2026-07-17T09:09:25.090874Z","end":"2026-07-17T09:09:25.704653Z","steps":["trace[541925411] 'process raft request' (duration: 613.227888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:25.704788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:25.242362Z","time spent":"462.42166ms","remote":"127.0.0.1:56210","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-17T09:09:25.705006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:25.090844Z","time spent":"613.993007ms","remote":"127.0.0.1:56062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T09:09:29.517511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394760216483152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T09:09:30.018628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394760216483152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T09:09:30.519559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394760216483152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T09:09:31.016805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999957809s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-17T09:09:31.016904Z","caller":"traceutil/trace.go:171","msg":"trace[1528417012] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000121955s","start":"2026-07-17T09:09:29.016758Z","end":"2026-07-17T09:09:31.01688Z","steps":["trace[1528417012] 'agreement among raft nodes before linearized reading' (duration: 1.99998154s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:31.016966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:29.016741Z","time spent":"2.000211977s","remote":"127.0.0.1:55986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T09:09:31.020106Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394760216483152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T09:09:31.439386Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.957155603s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T09:09:31.440856Z","caller":"traceutil/trace.go:171","msg":"trace[799214117] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"2.959793733s","start":"2026-07-17T09:09:28.481037Z","end":"2026-07-17T09:09:31.44083Z","steps":["trace[799214117] 'process raft request' (duration: 2.959087074s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:31.440984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:28.481016Z","time spent":"2.959893356s","remote":"127.0.0.1:56062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T09:09:31.814993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.870317ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-17T09:09:31.815289Z","caller":"traceutil/trace.go:171","msg":"trace[55991374] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"308.771925ms","start":"2026-07-17T09:09:31.506502Z","end":"2026-07-17T09:09:31.815274Z","steps":["trace[55991374] 'process raft request' (duration: 308.722253ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:31.81527Z","caller":"traceutil/trace.go:171","msg":"trace[327664064] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"794.46748ms","start":"2026-07-17T09:09:31.020763Z","end":"2026-07-17T09:09:31.815231Z","steps":["trace[327664064] 'process raft request' (duration: 794.314706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:31.815379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:31.506479Z","time spent":"308.854957ms","remote":"127.0.0.1:56426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T09:09:31.815422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:31.020744Z","time spent":"794.608654ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T09:09:31.815519Z","caller":"traceutil/trace.go:171","msg":"trace[1820017890] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"2.798512037s","start":"2026-07-17T09:09:29.016791Z","end":"2026-07-17T09:09:31.815303Z","steps":["trace[1820017890] 'read index received' (duration: 2.42311636s)","trace[1820017890] 'applied index is now lower than readState.Index' (duration: 375.392967ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:09:31.815781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.083411035s","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-07-17T09:09:31.815824Z","caller":"traceutil/trace.go:171","msg":"trace[145349363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"2.083493577s","start":"2026-07-17T09:09:29.732319Z","end":"2026-07-17T09:09:31.815812Z","steps":["trace[145349363] 'agreement among raft nodes before linearized reading' (duration: 2.083393325s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:31.815827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.857416748s","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-07-17T09:09:31.815854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:29.73229Z","time spent":"2.083556859s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T09:09:31.815866Z","caller":"traceutil/trace.go:171","msg":"trace[1616245701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"1.85749472s","start":"2026-07-17T09:09:29.95836Z","end":"2026-07-17T09:09:31.815854Z","steps":["trace[1616245701] 'agreement among raft nodes before linearized reading' (duration: 1.857403048s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:31.815873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.849885529s","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-17T09:09:31.815896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:29.958338Z","time spent":"1.857551691s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T09:09:31.815929Z","caller":"traceutil/trace.go:171","msg":"trace[1419293307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"1.849990452s","start":"2026-07-17T09:09:29.965925Z","end":"2026-07-17T09:09:31.815916Z","steps":["trace[1419293307] 'agreement among raft nodes before linearized reading' (duration: 1.849810477s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:31.815959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:29.965911Z","time spent":"1.850040744s","remote":"127.0.0.1:56210","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-17T09:09:31.815935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.076864292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-17T09:09:31.815969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.517182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T09:09:31.815997Z","caller":"traceutil/trace.go:171","msg":"trace[1744602552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"2.076969884s","start":"2026-07-17T09:09:29.739012Z","end":"2026-07-17T09:09:31.815982Z","steps":["trace[1744602552] 'agreement among raft nodes before linearized reading' (duration: 2.076833711s)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:31.816016Z","caller":"traceutil/trace.go:171","msg":"trace[72361714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"788.632235ms","start":"2026-07-17T09:09:31.027372Z","end":"2026-07-17T09:09:31.816004Z","steps":["trace[72361714] 'agreement among raft nodes before linearized reading' (duration: 788.511312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:31.81604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:29.738994Z","time spent":"2.077038167s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T09:09:31.816054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:31.027349Z","time spent":"788.697847ms","remote":"127.0.0.1:55974","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-17T09:09:32.009906Z","caller":"traceutil/trace.go:171","msg":"trace[367219894] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"190.133849ms","start":"2026-07-17T09:09:31.819751Z","end":"2026-07-17T09:09:32.009885Z","steps":["trace[367219894] 'process raft request' (duration: 135.274408ms)","trace[367219894] 'compare' (duration: 54.723207ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T09:09:32.010208Z","caller":"traceutil/trace.go:171","msg":"trace[478542470] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"189.654255ms","start":"2026-07-17T09:09:31.820544Z","end":"2026-07-17T09:09:32.010199Z","steps":["trace[478542470] 'process raft request' (duration: 189.494811ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:32.010176Z","caller":"traceutil/trace.go:171","msg":"trace[151175087] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:668; }","duration":"184.985642ms","start":"2026-07-17T09:09:31.825163Z","end":"2026-07-17T09:09:32.010149Z","steps":["trace[151175087] 'read index received' (duration: 71.162842ms)","trace[151175087] 'applied index is now lower than readState.Index' (duration: 113.82167ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T09:09:32.010286Z","caller":"traceutil/trace.go:171","msg":"trace[1252917476] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"190.305382ms","start":"2026-07-17T09:09:31.819974Z","end":"2026-07-17T09:09:32.010279Z","steps":["trace[1252917476] 'process raft request' (duration: 189.876492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:32.010306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.095275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T09:09:32.010377Z","caller":"traceutil/trace.go:171","msg":"trace[215909685] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:646; }","duration":"185.219288ms","start":"2026-07-17T09:09:31.825139Z","end":"2026-07-17T09:09:32.010358Z","steps":["trace[215909685] 'agreement among raft nodes before linearized reading' (duration: 185.090564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:32.346927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.644935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nbf25\" ","response":"range_response_count:1 size:17145"} {"level":"info","ts":"2026-07-17T09:09:32.347002Z","caller":"traceutil/trace.go:171","msg":"trace[1010146254] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nbf25; range_end:; response_count:1; response_revision:646; }","duration":"214.762719ms","start":"2026-07-17T09:09:32.132225Z","end":"2026-07-17T09:09:32.346988Z","steps":["trace[1010146254] 'range keys from in-memory index tree' (duration: 214.446321ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:32.34707Z","caller":"traceutil/trace.go:171","msg":"trace[751820769] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"213.565638ms","start":"2026-07-17T09:09:32.133459Z","end":"2026-07-17T09:09:32.347025Z","steps":["trace[751820769] 'process raft request' (duration: 189.657856ms)","trace[751820769] 'compare' (duration: 23.602144ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:09:33.519792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.538282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T09:09:33.519893Z","caller":"traceutil/trace.go:171","msg":"trace[34401856] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"267.860374ms","start":"2026-07-17T09:09:33.252017Z","end":"2026-07-17T09:09:33.519877Z","steps":["trace[34401856] 'process raft request' (duration: 58.184679ms)","trace[34401856] 'compare' (duration: 209.380257ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T09:09:41.683187Z","caller":"traceutil/trace.go:171","msg":"trace[1527488951] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"106.120057ms","start":"2026-07-17T09:09:41.577032Z","end":"2026-07-17T09:09:41.683152Z","steps":["trace[1527488951] 'process raft request' (duration: 101.893875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:42.741966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.234365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T09:09:42.74206Z","caller":"traceutil/trace.go:171","msg":"trace[229375757] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:773; }","duration":"169.352608ms","start":"2026-07-17T09:09:42.572688Z","end":"2026-07-17T09:09:42.74204Z","steps":["trace[229375757] 'count revisions from in-memory index tree' (duration: 169.138012ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:42.742139Z","caller":"traceutil/trace.go:171","msg":"trace[379484980] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"158.076691ms","start":"2026-07-17T09:09:42.584016Z","end":"2026-07-17T09:09:42.742092Z","steps":["trace[379484980] 'process raft request' (duration: 137.385504ms)","trace[379484980] 'compare' (duration: 20.413929ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T09:09:43.878106Z","caller":"traceutil/trace.go:171","msg":"trace[142754337] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"153.50924ms","start":"2026-07-17T09:09:43.724574Z","end":"2026-07-17T09:09:43.878084Z","steps":["trace[142754337] 'process raft request' (duration: 153.358436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:47.510789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.448129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-j95st\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-17T09:09:47.510898Z","caller":"traceutil/trace.go:171","msg":"trace[698269074] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-j95st; range_end:; response_count:1; response_revision:806; }","duration":"282.565402ms","start":"2026-07-17T09:09:47.228289Z","end":"2026-07-17T09:09:47.510854Z","steps":["trace[698269074] 'range keys from in-memory index tree' (duration: 282.315336ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:47.720408Z","caller":"traceutil/trace.go:171","msg":"trace[159012318] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"210.684521ms","start":"2026-07-17T09:09:47.509703Z","end":"2026-07-17T09:09:47.720387Z","steps":["trace[159012318] 'process raft request' (duration: 210.546448ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:09:47.825156Z","caller":"traceutil/trace.go:171","msg":"trace[95978484] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"305.202111ms","start":"2026-07-17T09:09:47.519931Z","end":"2026-07-17T09:09:47.825133Z","steps":["trace[95978484] 'process raft request' (duration: 305.054518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:09:47.825298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:09:47.519908Z","time spent":"305.319194ms","remote":"127.0.0.1:56138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T09:10:22.433549Z","caller":"traceutil/trace.go:171","msg":"trace[1496046886] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"205.805212ms","start":"2026-07-17T09:10:22.227726Z","end":"2026-07-17T09:10:22.433531Z","steps":["trace[1496046886] 'read index received' (duration: 205.642648ms)","trace[1496046886] 'applied index is now lower than readState.Index' (duration: 161.914µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T09:10:22.433763Z","caller":"traceutil/trace.go:171","msg":"trace[1479812605] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"352.80214ms","start":"2026-07-17T09:10:22.080953Z","end":"2026-07-17T09:10:22.433755Z","steps":["trace[1479812605] 'process raft request' (duration: 352.462821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:22.433838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:22.080936Z","time spent":"352.849312ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T09:10:22.434013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.289726ms","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-17T09:10:22.434034Z","caller":"traceutil/trace.go:171","msg":"trace[1410211976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1018; }","duration":"206.344547ms","start":"2026-07-17T09:10:22.227683Z","end":"2026-07-17T09:10:22.434028Z","steps":["trace[1410211976] 'agreement among raft nodes before linearized reading' (duration: 206.263105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:22.434152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.85446ms","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-17T09:10:22.434169Z","caller":"traceutil/trace.go:171","msg":"trace[1590866207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1018; }","duration":"136.902211ms","start":"2026-07-17T09:10:22.297262Z","end":"2026-07-17T09:10:22.434164Z","steps":["trace[1590866207] 'agreement among raft nodes before linearized reading' (duration: 136.85225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:22.434368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.310845ms","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-07-17T09:10:22.434441Z","caller":"traceutil/trace.go:171","msg":"trace[815589099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1018; }","duration":"133.358587ms","start":"2026-07-17T09:10:22.301037Z","end":"2026-07-17T09:10:22.434395Z","steps":["trace[815589099] 'agreement among raft nodes before linearized reading' (duration: 133.288225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:22.434528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.959292ms","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-07-17T09:10:22.434545Z","caller":"traceutil/trace.go:171","msg":"trace[289661120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"134.011304ms","start":"2026-07-17T09:10:22.300529Z","end":"2026-07-17T09:10:22.434541Z","steps":["trace[289661120] 'agreement among raft nodes before linearized reading' (duration: 133.960802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:22.607128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.023679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T09:10:22.607223Z","caller":"traceutil/trace.go:171","msg":"trace[946488887] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"172.274606ms","start":"2026-07-17T09:10:22.434929Z","end":"2026-07-17T09:10:22.607204Z","steps":["trace[946488887] 'process raft request' (duration: 59.098753ms)","trace[946488887] 'compare' (duration: 111.772226ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T09:10:22.759654Z","caller":"traceutil/trace.go:171","msg":"trace[564579509] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"321.539274ms","start":"2026-07-17T09:10:22.438095Z","end":"2026-07-17T09:10:22.759635Z","steps":["trace[564579509] 'process raft request' (duration: 321.487352ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:10:22.759761Z","caller":"traceutil/trace.go:171","msg":"trace[1341192655] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"321.704858ms","start":"2026-07-17T09:10:22.438033Z","end":"2026-07-17T09:10:22.759738Z","steps":["trace[1341192655] 'process raft request' (duration: 295.935696ms)","trace[1341192655] 'compare' (duration: 25.454753ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:10:22.759874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:22.438012Z","time spent":"321.802301ms","remote":"127.0.0.1:56210","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-17T09:10:22.759973Z","caller":"traceutil/trace.go:171","msg":"trace[2100285479] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"321.791789ms","start":"2026-07-17T09:10:22.438175Z","end":"2026-07-17T09:10:22.759967Z","steps":["trace[2100285479] 'process raft request' (duration: 321.43753ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:10:22.759946Z","caller":"traceutil/trace.go:171","msg":"trace[882639562] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1059; }","duration":"244.306411ms","start":"2026-07-17T09:10:22.515583Z","end":"2026-07-17T09:10:22.759889Z","steps":["trace[882639562] 'read index received' (duration: 218.355665ms)","trace[882639562] 'applied index is now lower than readState.Index' (duration: 25.948866ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:10:22.760018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:22.438169Z","time spent":"321.832691ms","remote":"127.0.0.1:56210","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-17T09:10:22.760032Z","caller":"traceutil/trace.go:171","msg":"trace[2129245576] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"321.771409ms","start":"2026-07-17T09:10:22.438231Z","end":"2026-07-17T09:10:22.760002Z","steps":["trace[2129245576] 'process raft request' (duration: 321.545043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:22.760086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:22.438219Z","time spent":"321.84078ms","remote":"127.0.0.1:56210","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-17T09:10:22.760101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.497326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T09:10:22.760137Z","caller":"traceutil/trace.go:171","msg":"trace[2047867751] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1023; }","duration":"244.576908ms","start":"2026-07-17T09:10:22.515549Z","end":"2026-07-17T09:10:22.760126Z","steps":["trace[2047867751] 'agreement among raft nodes before linearized reading' (duration: 244.466055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:22.759804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:22.438078Z","time spent":"321.634426ms","remote":"127.0.0.1:56210","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-17T09:10:24.938649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394760216484009,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T09:10:26.059565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.482011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T09:10:26.059693Z","caller":"traceutil/trace.go:171","msg":"trace[1871073970] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1064; }","duration":"1.621427119s","start":"2026-07-17T09:10:24.438254Z","end":"2026-07-17T09:10:26.059681Z","steps":["trace[1871073970] 'read index received' (duration: 635.780984ms)","trace[1871073970] 'applied index is now lower than readState.Index' (duration: 985.645425ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T09:10:26.059757Z","caller":"traceutil/trace.go:171","msg":"trace[1193012290] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"1.448329482s","start":"2026-07-17T09:10:24.611421Z","end":"2026-07-17T09:10:26.059751Z","steps":["trace[1193012290] 'process raft request' (duration: 1.448219018s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:26.0598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:24.611363Z","time spent":"1.448415145s","remote":"127.0.0.1:56210","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-17T09:10:26.059951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.621696957s","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-17T09:10:26.059971Z","caller":"traceutil/trace.go:171","msg":"trace[223130470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1025; }","duration":"1.621751308s","start":"2026-07-17T09:10:24.438214Z","end":"2026-07-17T09:10:26.059965Z","steps":["trace[223130470] 'agreement among raft nodes before linearized reading' (duration: 1.621667916s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:26.059989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:24.438195Z","time spent":"1.62178995s","remote":"127.0.0.1:56210","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-17T09:10:26.060041Z","caller":"traceutil/trace.go:171","msg":"trace[1388800281] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"1.708630616s","start":"2026-07-17T09:10:24.351403Z","end":"2026-07-17T09:10:26.060034Z","steps":["trace[1388800281] 'process raft request' (duration: 722.620501ms)","trace[1388800281] 'compare' (duration: 985.171843ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:10:26.060073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:24.351386Z","time spent":"1.708669397s","remote":"127.0.0.1:56008","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-17T09:10:26.447824Z","caller":"traceutil/trace.go:171","msg":"trace[538396777] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"388.089493ms","start":"2026-07-17T09:10:26.059715Z","end":"2026-07-17T09:10:26.447804Z","steps":["trace[538396777] 'read index received' (duration: 385.900735ms)","trace[538396777] 'applied index is now lower than readState.Index' (duration: 2.187998ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T09:10:26.448045Z","caller":"traceutil/trace.go:171","msg":"trace[1537121878] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"1.329749556s","start":"2026-07-17T09:10:25.118288Z","end":"2026-07-17T09:10:26.448037Z","steps":["trace[1537121878] 'process raft request' (duration: 1.327385774s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:26.448132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:25.118239Z","time spent":"1.329835218s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T09:10:26.448195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684027004s","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-07-17T09:10:26.448252Z","caller":"traceutil/trace.go:171","msg":"trace[842617233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"1.684113567s","start":"2026-07-17T09:10:24.764126Z","end":"2026-07-17T09:10:26.44824Z","steps":["trace[842617233] 'agreement among raft nodes before linearized reading' (duration: 1.683921032s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:26.448284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:24.76408Z","time spent":"1.68419771s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T09:10:26.44834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684288612s","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-17T09:10:26.448365Z","caller":"traceutil/trace.go:171","msg":"trace[1084378053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1026; }","duration":"1.684326173s","start":"2026-07-17T09:10:24.764032Z","end":"2026-07-17T09:10:26.448358Z","steps":["trace[1084378053] 'agreement among raft nodes before linearized reading' (duration: 1.684234881s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:26.448387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:24.764025Z","time spent":"1.684357663s","remote":"127.0.0.1:56210","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-17T09:10:26.448426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.657061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T09:10:26.448446Z","caller":"traceutil/trace.go:171","msg":"trace[1208604849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"432.706593ms","start":"2026-07-17T09:10:26.015734Z","end":"2026-07-17T09:10:26.44844Z","steps":["trace[1208604849] 'agreement among raft nodes before linearized reading' (duration: 432.672632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:26.448462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:26.015715Z","time spent":"432.744143ms","remote":"127.0.0.1:55974","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-17T09:10:26.448519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684932729s","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-17T09:10:26.44854Z","caller":"traceutil/trace.go:171","msg":"trace[123453551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1026; }","duration":"1.68498032s","start":"2026-07-17T09:10:24.763553Z","end":"2026-07-17T09:10:26.448533Z","steps":["trace[123453551] 'agreement among raft nodes before linearized reading' (duration: 1.684910579s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:26.44856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:24.763537Z","time spent":"1.685019232s","remote":"127.0.0.1:56210","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-17T09:10:26.448617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328928204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T09:10:26.448636Z","caller":"traceutil/trace.go:171","msg":"trace[262575961] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1026; }","duration":"1.328981446s","start":"2026-07-17T09:10:25.119649Z","end":"2026-07-17T09:10:26.448631Z","steps":["trace[262575961] 'agreement among raft nodes before linearized reading' (duration: 1.328902644s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:26.448653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:25.119623Z","time spent":"1.329025866s","remote":"127.0.0.1:56434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":114,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-17T09:10:26.448652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.818105311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T09:10:26.448677Z","caller":"traceutil/trace.go:171","msg":"trace[823766088] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1026; }","duration":"1.818158283s","start":"2026-07-17T09:10:24.630511Z","end":"2026-07-17T09:10:26.44867Z","steps":["trace[823766088] 'agreement among raft nodes before linearized reading' (duration: 1.818122512s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:26.448712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:24.630496Z","time spent":"1.818205673s","remote":"127.0.0.1:56266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-07-17T09:10:26.448782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329120888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T09:10:26.448801Z","caller":"traceutil/trace.go:171","msg":"trace[1859401455] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1026; }","duration":"1.32916683s","start":"2026-07-17T09:10:25.119629Z","end":"2026-07-17T09:10:26.448795Z","steps":["trace[1859401455] 'agreement among raft nodes before linearized reading' (duration: 1.329109538s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:26.448823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:25.119617Z","time spent":"1.32920143s","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-07-17T09:10:26.448941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684528758s","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-07-17T09:10:26.448958Z","caller":"traceutil/trace.go:171","msg":"trace[927538490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"1.684544469s","start":"2026-07-17T09:10:24.764408Z","end":"2026-07-17T09:10:26.448952Z","steps":["trace[927538490] 'agreement among raft nodes before linearized reading' (duration: 1.684493487s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:26.448974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:24.764389Z","time spent":"1.68458114s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T09:10:26.454279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.275264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-17T09:10:26.454315Z","caller":"traceutil/trace.go:171","msg":"trace[1263297361] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1027; }","duration":"392.326435ms","start":"2026-07-17T09:10:26.061978Z","end":"2026-07-17T09:10:26.454305Z","steps":["trace[1263297361] 'agreement among raft nodes before linearized reading' (duration: 392.260883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:26.454343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:26.061965Z","time spent":"392.374007ms","remote":"127.0.0.1:56124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-17T09:10:26.454557Z","caller":"traceutil/trace.go:171","msg":"trace[884687141] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"389.960223ms","start":"2026-07-17T09:10:26.064587Z","end":"2026-07-17T09:10:26.454547Z","steps":["trace[884687141] 'process raft request' (duration: 389.487331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:26.456079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:26.064574Z","time spent":"390.026185ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T09:10:48.826705Z","caller":"traceutil/trace.go:171","msg":"trace[401224985] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"269.871546ms","start":"2026-07-17T09:10:48.556808Z","end":"2026-07-17T09:10:48.826679Z","steps":["trace[401224985] 'process raft request' (duration: 269.688131ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:10:49.006859Z","caller":"traceutil/trace.go:171","msg":"trace[1609708105] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1144; }","duration":"449.694425ms","start":"2026-07-17T09:10:48.557147Z","end":"2026-07-17T09:10:49.006841Z","steps":["trace[1609708105] 'read index received' (duration: 269.316521ms)","trace[1609708105] 'applied index is now lower than readState.Index' (duration: 180.377064ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:10:49.007028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.870569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T09:10:49.007059Z","caller":"traceutil/trace.go:171","msg":"trace[1318697431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1103; }","duration":"449.932601ms","start":"2026-07-17T09:10:48.557118Z","end":"2026-07-17T09:10:49.007051Z","steps":["trace[1318697431] 'agreement among raft nodes before linearized reading' (duration: 449.795658ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:10:49.007048Z","caller":"traceutil/trace.go:171","msg":"trace[1219663393] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"449.242823ms","start":"2026-07-17T09:10:48.55778Z","end":"2026-07-17T09:10:49.007023Z","steps":["trace[1219663393] 'process raft request' (duration: 448.946145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:49.00709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:48.556852Z","time spent":"450.231079ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T09:10:49.007186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:48.557749Z","time spent":"449.362576ms","remote":"127.0.0.1:56210","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-17T09:10:49.0073Z","caller":"traceutil/trace.go:171","msg":"trace[487870569] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"450.246729ms","start":"2026-07-17T09:10:48.557042Z","end":"2026-07-17T09:10:49.007288Z","steps":["trace[487870569] 'process raft request' (duration: 442.950667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:49.007377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:48.557022Z","time spent":"450.30406ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T09:10:49.007565Z","caller":"traceutil/trace.go:171","msg":"trace[2075467477] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"448.877553ms","start":"2026-07-17T09:10:48.558312Z","end":"2026-07-17T09:10:49.007189Z","steps":["trace[2075467477] 'process raft request' (duration: 448.486153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:10:49.008254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:10:48.558302Z","time spent":"449.382566ms","remote":"127.0.0.1:56210","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-17T09:10:50.342741Z","caller":"traceutil/trace.go:171","msg":"trace[1409983173] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"190.313206ms","start":"2026-07-17T09:10:50.152401Z","end":"2026-07-17T09:10:50.342715Z","steps":["trace[1409983173] 'process raft request' (duration: 190.112841ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:11:06.654096Z","caller":"traceutil/trace.go:171","msg":"trace[2018104110] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"257.476379ms","start":"2026-07-17T09:11:06.396594Z","end":"2026-07-17T09:11:06.65407Z","steps":["trace[2018104110] 'process raft request' (duration: 257.351945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:07.117191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.796136ms","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-17T09:11:07.117257Z","caller":"traceutil/trace.go:171","msg":"trace[1450940929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1208; }","duration":"210.899299ms","start":"2026-07-17T09:11:06.906344Z","end":"2026-07-17T09:11:07.117244Z","steps":["trace[1450940929] 'range keys from in-memory index tree' (duration: 210.685953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:07.117178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.098654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T09:11:07.11802Z","caller":"traceutil/trace.go:171","msg":"trace[1423626302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"100.982516ms","start":"2026-07-17T09:11:07.017027Z","end":"2026-07-17T09:11:07.11801Z","steps":["trace[1423626302] 'range keys from in-memory index tree' (duration: 100.018831ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:11:24.565386Z","caller":"traceutil/trace.go:171","msg":"trace[2104417101] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"257.362527ms","start":"2026-07-17T09:11:24.307995Z","end":"2026-07-17T09:11:24.565358Z","steps":["trace[2104417101] 'process raft request' (duration: 257.183331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:25.486488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.985213ms","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-17T09:11:25.48653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.636554ms","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-17T09:11:25.486592Z","caller":"traceutil/trace.go:171","msg":"trace[1270608839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1289; }","duration":"279.740227ms","start":"2026-07-17T09:11:25.206841Z","end":"2026-07-17T09:11:25.486581Z","steps":["trace[1270608839] 'range keys from in-memory index tree' (duration: 279.569632ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:11:25.486592Z","caller":"traceutil/trace.go:171","msg":"trace[349087451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1289; }","duration":"280.089697ms","start":"2026-07-17T09:11:25.206466Z","end":"2026-07-17T09:11:25.486555Z","steps":["trace[349087451] 'range keys from in-memory index tree' (duration: 279.852889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:25.486839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.772992ms","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-17T09:11:25.486847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.531114ms","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-17T09:11:25.486873Z","caller":"traceutil/trace.go:171","msg":"trace[829641097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1289; }","duration":"247.839114ms","start":"2026-07-17T09:11:25.239025Z","end":"2026-07-17T09:11:25.486864Z","steps":["trace[829641097] 'range keys from in-memory index tree' (duration: 247.629079ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:11:25.486886Z","caller":"traceutil/trace.go:171","msg":"trace[997032060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1289; }","duration":"281.597516ms","start":"2026-07-17T09:11:25.205278Z","end":"2026-07-17T09:11:25.486875Z","steps":["trace[997032060] 'range keys from in-memory index tree' (duration: 281.433891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:25.487235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.38389ms","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-17T09:11:25.487317Z","caller":"traceutil/trace.go:171","msg":"trace[1644346491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1289; }","duration":"278.483584ms","start":"2026-07-17T09:11:25.208814Z","end":"2026-07-17T09:11:25.487298Z","steps":["trace[1644346491] 'range keys from in-memory index tree' (duration: 277.919729ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:11:26.164697Z","caller":"traceutil/trace.go:171","msg":"trace[1312500220] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"671.241765ms","start":"2026-07-17T09:11:25.493433Z","end":"2026-07-17T09:11:26.164675Z","steps":["trace[1312500220] 'process raft request' (duration: 671.191514ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:11:26.16479Z","caller":"traceutil/trace.go:171","msg":"trace[2010427484] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"671.300617ms","start":"2026-07-17T09:11:25.493442Z","end":"2026-07-17T09:11:26.164742Z","steps":["trace[2010427484] 'process raft request' (duration: 671.077241ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:11:26.164833Z","caller":"traceutil/trace.go:171","msg":"trace[1921533559] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"671.374408ms","start":"2026-07-17T09:11:25.493434Z","end":"2026-07-17T09:11:26.164808Z","steps":["trace[1921533559] 'process raft request' (duration: 671.216004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:26.164897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:25.49341Z","time spent":"671.396268ms","remote":"127.0.0.1:56210","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-17T09:11:26.164992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:25.493415Z","time spent":"671.46961ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T09:11:26.164992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:25.493432Z","time spent":"671.45267ms","remote":"127.0.0.1:56210","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-17T09:11:26.613221Z","caller":"traceutil/trace.go:171","msg":"trace[1991353015] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1345; }","duration":"595.819363ms","start":"2026-07-17T09:11:26.017374Z","end":"2026-07-17T09:11:26.613193Z","steps":["trace[1991353015] 'read index received' (duration: 147.247458ms)","trace[1991353015] 'applied index is now lower than readState.Index' (duration: 448.570965ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:11:26.613388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.993117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T09:11:26.613422Z","caller":"traceutil/trace.go:171","msg":"trace[1020687654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"596.09638ms","start":"2026-07-17T09:11:26.017316Z","end":"2026-07-17T09:11:26.613412Z","steps":["trace[1020687654] 'agreement among raft nodes before linearized reading' (duration: 595.988887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:26.613458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:26.017295Z","time spent":"596.155251ms","remote":"127.0.0.1:55974","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-17T09:11:26.613555Z","caller":"traceutil/trace.go:171","msg":"trace[76218720] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"1.120012775s","start":"2026-07-17T09:11:25.4935Z","end":"2026-07-17T09:11:26.613513Z","steps":["trace[76218720] 'process raft request' (duration: 1.119515742s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:26.613734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:25.49347Z","time spent":"1.120174199s","remote":"127.0.0.1:56210","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-17T09:11:26.613762Z","caller":"traceutil/trace.go:171","msg":"trace[703555720] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"1.119893402s","start":"2026-07-17T09:11:25.493857Z","end":"2026-07-17T09:11:26.61375Z","steps":["trace[703555720] 'process raft request' (duration: 1.119298246s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:26.613846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:25.493847Z","time spent":"1.119942032s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T09:11:26.862789Z","caller":"traceutil/trace.go:171","msg":"trace[1353618177] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"113.332993ms","start":"2026-07-17T09:11:26.749434Z","end":"2026-07-17T09:11:26.862767Z","steps":["trace[1353618177] 'process raft request' (duration: 113.20996ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:11:27.443586Z","caller":"traceutil/trace.go:171","msg":"trace[79041775] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"534.381971ms","start":"2026-07-17T09:11:26.909178Z","end":"2026-07-17T09:11:27.44356Z","steps":["trace[79041775] 'process raft request' (duration: 491.296173ms)","trace[79041775] 'compare' (duration: 42.771799ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:11:27.443743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:26.909149Z","time spent":"534.544854ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T09:11:27.443728Z","caller":"traceutil/trace.go:171","msg":"trace[129202673] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"450.936327ms","start":"2026-07-17T09:11:26.992759Z","end":"2026-07-17T09:11:27.443695Z","steps":["trace[129202673] 'read index received' (duration: 407.702315ms)","trace[129202673] 'applied index is now lower than readState.Index' (duration: 43.229672ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:11:27.443927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.152932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T09:11:27.44397Z","caller":"traceutil/trace.go:171","msg":"trace[777957436] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1297; }","duration":"451.238445ms","start":"2026-07-17T09:11:26.992719Z","end":"2026-07-17T09:11:27.443957Z","steps":["trace[777957436] 'agreement among raft nodes before linearized reading' (duration: 451.100011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:27.44401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:26.992702Z","time spent":"451.300476ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-17T09:11:27.922147Z","caller":"traceutil/trace.go:171","msg":"trace[1813403039] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"473.77837ms","start":"2026-07-17T09:11:27.448347Z","end":"2026-07-17T09:11:27.922125Z","steps":["trace[1813403039] 'process raft request' (duration: 473.661507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:27.922265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:27.448325Z","time spent":"473.891713ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T09:11:27.922726Z","caller":"traceutil/trace.go:171","msg":"trace[640292133] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1352; }","duration":"173.327535ms","start":"2026-07-17T09:11:27.749388Z","end":"2026-07-17T09:11:27.922715Z","steps":["trace[640292133] 'read index received' (duration: 172.613957ms)","trace[640292133] 'applied index is now lower than readState.Index' (duration: 712.808µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T09:11:27.922733Z","caller":"traceutil/trace.go:171","msg":"trace[1878307209] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"208.021563ms","start":"2026-07-17T09:11:27.7147Z","end":"2026-07-17T09:11:27.922722Z","steps":["trace[1878307209] 'process raft request' (duration: 207.971071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:27.922826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.431869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T09:11:27.92285Z","caller":"traceutil/trace.go:171","msg":"trace[860518330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"173.503221ms","start":"2026-07-17T09:11:27.749339Z","end":"2026-07-17T09:11:27.922843Z","steps":["trace[860518330] 'agreement among raft nodes before linearized reading' (duration: 173.411468ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:11:28.406256Z","caller":"traceutil/trace.go:171","msg":"trace[821392827] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1355; }","duration":"393.446889ms","start":"2026-07-17T09:11:28.012791Z","end":"2026-07-17T09:11:28.406238Z","steps":["trace[821392827] 'read index received' (duration: 393.277254ms)","trace[821392827] 'applied index is now lower than readState.Index' (duration: 169.115µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T09:11:28.406353Z","caller":"traceutil/trace.go:171","msg":"trace[1333499964] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"472.876126ms","start":"2026-07-17T09:11:27.93347Z","end":"2026-07-17T09:11:28.406346Z","steps":["trace[1333499964] 'process raft request' (duration: 472.731123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:28.406414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:27.933464Z","time spent":"472.902337ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T09:11:28.406443Z","caller":"traceutil/trace.go:171","msg":"trace[566243423] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"473.134523ms","start":"2026-07-17T09:11:27.933276Z","end":"2026-07-17T09:11:28.406411Z","steps":["trace[566243423] 'process raft request' (duration: 472.792454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:28.40653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.267445ms","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-17T09:11:28.406546Z","caller":"traceutil/trace.go:171","msg":"trace[475482364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1302; }","duration":"237.301716ms","start":"2026-07-17T09:11:28.16924Z","end":"2026-07-17T09:11:28.406542Z","steps":["trace[475482364] 'agreement among raft nodes before linearized reading' (duration: 237.235165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:28.406516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.45466ms","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-17T09:11:28.406615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.825989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T09:11:28.406609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:27.933256Z","time spent":"473.263877ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T09:11:28.406629Z","caller":"traceutil/trace.go:171","msg":"trace[1282847528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"393.870519ms","start":"2026-07-17T09:11:28.012754Z","end":"2026-07-17T09:11:28.406624Z","steps":["trace[1282847528] 'agreement among raft nodes before linearized reading' (duration: 393.848899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:28.406674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:28.012737Z","time spent":"393.906002ms","remote":"127.0.0.1:55986","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-17T09:11:28.406714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.637495ms","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-17T09:11:28.40661Z","caller":"traceutil/trace.go:171","msg":"trace[860573955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1302; }","duration":"237.595673ms","start":"2026-07-17T09:11:28.168996Z","end":"2026-07-17T09:11:28.406592Z","steps":["trace[860573955] 'agreement among raft nodes before linearized reading' (duration: 237.383578ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:11:28.406746Z","caller":"traceutil/trace.go:171","msg":"trace[1852378044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"237.683156ms","start":"2026-07-17T09:11:28.169054Z","end":"2026-07-17T09:11:28.406737Z","steps":["trace[1852378044] 'agreement among raft nodes before linearized reading' (duration: 237.588104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:38.923879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394760216484792,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T09:11:39.424431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394760216484792,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T09:11:39.748123Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.458511988s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T09:11:39.749811Z","caller":"traceutil/trace.go:171","msg":"trace[445904952] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"1.460213864s","start":"2026-07-17T09:11:38.289475Z","end":"2026-07-17T09:11:39.749689Z","steps":["trace[445904952] 'process raft request' (duration: 1.459133795s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:39.750325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:38.289453Z","time spent":"1.460523921s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T09:11:39.860099Z","caller":"traceutil/trace.go:171","msg":"trace[1099189356] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"940.490232ms","start":"2026-07-17T09:11:38.919581Z","end":"2026-07-17T09:11:39.860071Z","steps":["trace[1099189356] 'process raft request' (duration: 939.013993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:39.860314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:38.919554Z","time spent":"940.621575ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T09:11:39.860323Z","caller":"traceutil/trace.go:171","msg":"trace[1450523906] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"380.360806ms","start":"2026-07-17T09:11:39.479924Z","end":"2026-07-17T09:11:39.860285Z","steps":["trace[1450523906] 'process raft request' (duration: 379.945454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:39.86052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:39.479902Z","time spent":"380.520149ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T09:11:39.862893Z","caller":"traceutil/trace.go:171","msg":"trace[2016196726] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1430; }","duration":"1.439938908s","start":"2026-07-17T09:11:38.422922Z","end":"2026-07-17T09:11:39.862861Z","steps":["trace[2016196726] 'read index received' (duration: 1.325664493s)","trace[2016196726] 'applied index is now lower than readState.Index' (duration: 114.272915ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T09:11:39.863143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.182379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T09:11:39.863195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407530825s","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-17T09:11:39.863246Z","caller":"traceutil/trace.go:171","msg":"trace[1918867498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1376; }","duration":"1.407637628s","start":"2026-07-17T09:11:38.455596Z","end":"2026-07-17T09:11:39.863234Z","steps":["trace[1918867498] 'agreement among raft nodes before linearized reading' (duration: 1.407476064s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:39.863278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:38.455575Z","time spent":"1.407695999s","remote":"127.0.0.1:56210","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-17T09:11:39.863288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.733748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T09:11:39.863343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.102823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-17T09:11:39.863347Z","caller":"traceutil/trace.go:171","msg":"trace[1106704623] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1376; }","duration":"476.83915ms","start":"2026-07-17T09:11:39.386495Z","end":"2026-07-17T09:11:39.863334Z","steps":["trace[1106704623] 'agreement among raft nodes before linearized reading' (duration: 476.752098ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:11:39.863378Z","caller":"traceutil/trace.go:171","msg":"trace[102880538] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1376; }","duration":"420.174065ms","start":"2026-07-17T09:11:39.443191Z","end":"2026-07-17T09:11:39.863365Z","steps":["trace[102880538] 'agreement among raft nodes before linearized reading' (duration: 420.040892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:39.863382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203372588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-17T09:11:39.863409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:39.386472Z","time spent":"476.924783ms","remote":"127.0.0.1:56176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-17T09:11:39.863414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:39.44317Z","time spent":"420.234667ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-17T09:11:39.863426Z","caller":"traceutil/trace.go:171","msg":"trace[1197344192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1376; }","duration":"1.203426499s","start":"2026-07-17T09:11:38.659986Z","end":"2026-07-17T09:11:39.863413Z","steps":["trace[1197344192] 'agreement among raft nodes before linearized reading' (duration: 1.203274175s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:39.863442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440529234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-07-17T09:11:39.863463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:38.659981Z","time spent":"1.20347501s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T09:11:39.8635Z","caller":"traceutil/trace.go:171","msg":"trace[233000488] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1376; }","duration":"1.440613657s","start":"2026-07-17T09:11:38.422867Z","end":"2026-07-17T09:11:39.863481Z","steps":["trace[233000488] 'agreement among raft nodes before linearized reading' (duration: 1.440519134s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:39.863511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406965408s","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-07-17T09:11:39.86355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:38.422847Z","time spent":"1.440694529s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T09:11:39.863324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.993131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-17T09:11:39.863617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203655965s","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-07-17T09:11:39.863654Z","caller":"traceutil/trace.go:171","msg":"trace[2009545024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1376; }","duration":"1.203730777s","start":"2026-07-17T09:11:38.659914Z","end":"2026-07-17T09:11:39.863645Z","steps":["trace[2009545024] 'agreement among raft nodes before linearized reading' (duration: 1.203627544s)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:11:39.863655Z","caller":"traceutil/trace.go:171","msg":"trace[1966640127] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1376; }","duration":"102.35062ms","start":"2026-07-17T09:11:39.761259Z","end":"2026-07-17T09:11:39.863609Z","steps":["trace[1966640127] 'agreement among raft nodes before linearized reading' (duration: 101.855918ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:11:39.863556Z","caller":"traceutil/trace.go:171","msg":"trace[1972804138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1376; }","duration":"1.40704427s","start":"2026-07-17T09:11:38.456501Z","end":"2026-07-17T09:11:39.863545Z","steps":["trace[1972804138] 'agreement among raft nodes before linearized reading' (duration: 1.406906257s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:39.863933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:38.456481Z","time spent":"1.407429772s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T09:11:39.863649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406755123s","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-07-17T09:11:39.864033Z","caller":"traceutil/trace.go:171","msg":"trace[1141724428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1376; }","duration":"1.407163255s","start":"2026-07-17T09:11:38.456836Z","end":"2026-07-17T09:11:39.863999Z","steps":["trace[1141724428] 'agreement among raft nodes before linearized reading' (duration: 1.406372704s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:39.863222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359813466s","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":"warn","ts":"2026-07-17T09:11:39.864086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:38.45683Z","time spent":"1.407240986s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T09:11:39.864115Z","caller":"traceutil/trace.go:171","msg":"trace[1370696928] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1376; }","duration":"1.36075361s","start":"2026-07-17T09:11:38.503349Z","end":"2026-07-17T09:11:39.864103Z","steps":["trace[1370696928] 'agreement among raft nodes before linearized reading' (duration: 1.359810306s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:39.864157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:38.503329Z","time spent":"1.360820221s","remote":"127.0.0.1:46814","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":"warn","ts":"2026-07-17T09:11:39.864563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:38.659894Z","time spent":"1.203795809s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T09:11:39.86319Z","caller":"traceutil/trace.go:171","msg":"trace[2034445289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"845.278291ms","start":"2026-07-17T09:11:39.0179Z","end":"2026-07-17T09:11:39.863178Z","steps":["trace[2034445289] 'agreement among raft nodes before linearized reading' (duration: 845.180479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:11:39.864869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:11:39.017883Z","time spent":"846.974415ms","remote":"127.0.0.1:55974","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-17T09:13:34.881753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394760216486078,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T09:13:34.955992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:13:34.202158Z","time spent":"753.828052ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-17T09:13:34.957721Z","caller":"traceutil/trace.go:171","msg":"trace[325744421] linearizableReadLoop","detail":"{readStateIndex:1981; appliedIndex:1979; }","duration":"576.052157ms","start":"2026-07-17T09:13:34.381651Z","end":"2026-07-17T09:13:34.957703Z","steps":["trace[325744421] 'read index received' (duration: 574.298382ms)","trace[325744421] 'applied index is now lower than readState.Index' (duration: 1.753055ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T09:13:34.957813Z","caller":"traceutil/trace.go:171","msg":"trace[2027515161] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"708.471256ms","start":"2026-07-17T09:13:34.249288Z","end":"2026-07-17T09:13:34.957759Z","steps":["trace[2027515161] 'process raft request' (duration: 708.269741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:13:34.957984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.240953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-17T09:13:34.958022Z","caller":"traceutil/trace.go:171","msg":"trace[1360025134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1900; }","duration":"576.401247ms","start":"2026-07-17T09:13:34.381611Z","end":"2026-07-17T09:13:34.958013Z","steps":["trace[1360025134] 'agreement among raft nodes before linearized reading' (duration: 576.173661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:13:34.958049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:13:34.381592Z","time spent":"576.448288ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T09:13:34.958088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:13:34.249263Z","time spent":"708.658001ms","remote":"127.0.0.1:56210","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-17T09:13:34.959491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.345923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-17T09:13:34.959437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.651193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-17T09:13:34.959696Z","caller":"traceutil/trace.go:171","msg":"trace[678633897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1901; }","duration":"576.92627ms","start":"2026-07-17T09:13:34.38275Z","end":"2026-07-17T09:13:34.959677Z","steps":["trace[678633897] 'agreement among raft nodes before linearized reading' (duration: 576.504629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:13:34.95974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:13:34.382727Z","time spent":"577.000072ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T09:13:34.959613Z","caller":"traceutil/trace.go:171","msg":"trace[1007109963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1901; }","duration":"527.494167ms","start":"2026-07-17T09:13:34.432098Z","end":"2026-07-17T09:13:34.959592Z","steps":["trace[1007109963] 'agreement among raft nodes before linearized reading' (duration: 527.21584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:13:34.959871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:13:34.432092Z","time spent":"527.755923ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T09:13:34.960033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.379187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-17T09:13:34.960048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.199231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T09:13:34.960095Z","caller":"traceutil/trace.go:171","msg":"trace[2008952724] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1901; }","duration":"417.442179ms","start":"2026-07-17T09:13:34.542641Z","end":"2026-07-17T09:13:34.960083Z","steps":["trace[2008952724] 'agreement among raft nodes before linearized reading' (duration: 417.220532ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T09:13:34.960079Z","caller":"traceutil/trace.go:171","msg":"trace[589097491] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1901; }","duration":"529.43941ms","start":"2026-07-17T09:13:34.430633Z","end":"2026-07-17T09:13:34.960073Z","steps":["trace[589097491] 'agreement among raft nodes before linearized reading' (duration: 529.344437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:13:34.960142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:13:34.430626Z","time spent":"529.509391ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-17T09:13:34.960131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:13:34.542621Z","time spent":"417.50225ms","remote":"127.0.0.1:57512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-17T09:13:34.960223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.501573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T09:13:34.960243Z","caller":"traceutil/trace.go:171","msg":"trace[1774165196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1901; }","duration":"531.631597ms","start":"2026-07-17T09:13:34.428607Z","end":"2026-07-17T09:13:34.960238Z","steps":["trace[1774165196] 'agreement among raft nodes before linearized reading' (duration: 531.581815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:13:34.96026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:13:34.428594Z","time spent":"531.662187ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T09:13:34.960329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.407723ms","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-07-17T09:13:34.96036Z","caller":"traceutil/trace.go:171","msg":"trace[810392770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1901; }","duration":"536.479574ms","start":"2026-07-17T09:13:34.423872Z","end":"2026-07-17T09:13:34.960351Z","steps":["trace[810392770] 'agreement among raft nodes before linearized reading' (duration: 536.388762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T09:13:34.960383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T09:13:34.423848Z","time spent":"536.529885ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}