{"level":"warn","ts":"2026-08-14T09:35:38.938887Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:35:38.939013Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-08-14T09:35:38.939094Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:35:38.939103Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-08-14T09:35:38.939129Z","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-08-14T09:35:38.940051Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-08-14T09:35:38.94016Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-08-14T09:35:38.944111Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.28353ms"} {"level":"info","ts":"2026-08-14T09:35:38.950563Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-08-14T09:35:38.950654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T09:35:38.950683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-08-14T09:35:38.950694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T09:35:38.950704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-08-14T09:35:38.950751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-08-14T09:35:38.957116Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T09:35:38.958741Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T09:35:38.960356Z","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-08-14T09:35:38.966525Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T09:35:38.966866Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T09:35:38.96707Z","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-08-14T09:35:38.967117Z","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-08-14T09:35:38.967127Z","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-08-14T09:35:38.967719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-08-14T09:35:38.967833Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-08-14T09:35:38.97414Z","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-08-14T09:35:38.974467Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T09:35:38.974506Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T09:35:38.975641Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-08-14T09:35:38.975749Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-08-14T09:35:39.35093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T09:35:39.351033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T09:35:39.351135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-08-14T09:35:39.351182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-08-14T09:35:39.351205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-08-14T09:35:39.351234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-08-14T09:35:39.35126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-08-14T09:35:39.352723Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T09:35:39.35434Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:35:39.354643Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:35:39.355838Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:35:39.356332Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-08-14T09:35:39.356288Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T09:35:39.356449Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T09:35:39.356481Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:35:39.356697Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:35:39.356766Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:35:39.359066Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-14T09:35:55.880931Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845428051331406,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:35:55.939793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.556345ms","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-08-14T09:35:55.939929Z","caller":"traceutil/trace.go:171","msg":"trace[119554258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:368; }","duration":"896.7677ms","start":"2026-08-14T09:35:55.043137Z","end":"2026-08-14T09:35:55.939904Z","steps":["trace[119554258] 'range keys from in-memory index tree' (duration: 896.407651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:35:55.93999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:35:55.043106Z","time spent":"896.871332ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T09:35:55.974954Z","caller":"traceutil/trace.go:171","msg":"trace[2087620898] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"594.689358ms","start":"2026-08-14T09:35:55.380234Z","end":"2026-08-14T09:35:55.974924Z","steps":["trace[2087620898] 'read index received' (duration: 594.540734ms)","trace[2087620898] 'applied index is now lower than readState.Index' (duration: 147.084µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:35:55.975085Z","caller":"traceutil/trace.go:171","msg":"trace[648291460] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"713.274117ms","start":"2026-08-14T09:35:55.26175Z","end":"2026-08-14T09:35:55.975024Z","steps":["trace[648291460] 'process raft request' (duration: 712.978409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:35:55.975246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.980986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:35:55.975327Z","caller":"traceutil/trace.go:171","msg":"trace[735372904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:369; }","duration":"595.128609ms","start":"2026-08-14T09:35:55.380171Z","end":"2026-08-14T09:35:55.9753Z","steps":["trace[735372904] 'agreement among raft nodes before linearized reading' (duration: 594.923764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:35:55.975368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:35:55.380143Z","time spent":"595.217172ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:35:55.975897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:35:55.261713Z","time spent":"713.478852ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:35:55.976261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.75902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:35:55.976312Z","caller":"traceutil/trace.go:171","msg":"trace[263631312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:370; }","duration":"594.823191ms","start":"2026-08-14T09:35:55.381476Z","end":"2026-08-14T09:35:55.9763Z","steps":["trace[263631312] 'agreement among raft nodes before linearized reading' (duration: 594.723118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:35:55.976346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:35:55.38147Z","time spent":"594.868843ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:36:20.556537Z","caller":"traceutil/trace.go:171","msg":"trace[254534150] transaction","detail":"{read_only:false; number_of_response:1; response_revision:558; }","duration":"107.189015ms","start":"2026-08-14T09:36:20.449309Z","end":"2026-08-14T09:36:20.556498Z","steps":["trace[254534150] 'process raft request' (duration: 100.745136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:22.200217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845428051331810,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:36:22.620065Z","caller":"traceutil/trace.go:171","msg":"trace[1930903695] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"927.814592ms","start":"2026-08-14T09:36:21.692223Z","end":"2026-08-14T09:36:22.620037Z","steps":["trace[1930903695] 'process raft request' (duration: 927.482833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:22.620241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:21.692192Z","time spent":"927.952535ms","remote":"127.0.0.1:46158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:36:22.630932Z","caller":"traceutil/trace.go:171","msg":"trace[1240856726] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"797.556063ms","start":"2026-08-14T09:36:21.833352Z","end":"2026-08-14T09:36:22.630908Z","steps":["trace[1240856726] 'process raft request' (duration: 797.472301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:22.631062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:21.833316Z","time spent":"797.680376ms","remote":"127.0.0.1:46158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:36:22.631244Z","caller":"traceutil/trace.go:171","msg":"trace[294623312] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"852.243053ms","start":"2026-08-14T09:36:21.778938Z","end":"2026-08-14T09:36:22.631182Z","steps":["trace[294623312] 'process raft request' (duration: 851.475464ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:22.63146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:21.778833Z","time spent":"852.509848ms","remote":"127.0.0.1:46158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:36:22.632757Z","caller":"traceutil/trace.go:171","msg":"trace[1100865752] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:623; }","duration":"932.626521ms","start":"2026-08-14T09:36:21.700111Z","end":"2026-08-14T09:36:22.632738Z","steps":["trace[1100865752] 'read index received' (duration: 919.70505ms)","trace[1100865752] 'applied index is now lower than readState.Index' (duration: 12.919181ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:36:22.63816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.012435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-08-14T09:36:22.638262Z","caller":"traceutil/trace.go:171","msg":"trace[1604319815] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:604; }","duration":"938.169309ms","start":"2026-08-14T09:36:21.700057Z","end":"2026-08-14T09:36:22.638227Z","steps":["trace[1604319815] 'agreement among raft nodes before linearized reading' (duration: 932.818845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:22.638343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:21.70003Z","time spent":"938.299822ms","remote":"127.0.0.1:46158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151258,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-08-14T09:36:22.631307Z","caller":"traceutil/trace.go:171","msg":"trace[86418220] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"329.933684ms","start":"2026-08-14T09:36:22.301361Z","end":"2026-08-14T09:36:22.631295Z","steps":["trace[86418220] 'process raft request' (duration: 329.487093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:22.63971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:22.301339Z","time spent":"338.221211ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:36:22.642609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.804714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:36:22.642743Z","caller":"traceutil/trace.go:171","msg":"trace[794594171] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:604; }","duration":"614.020278ms","start":"2026-08-14T09:36:22.028691Z","end":"2026-08-14T09:36:22.642712Z","steps":["trace[794594171] 'agreement among raft nodes before linearized reading' (duration: 613.811963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:22.642799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:22.028661Z","time spent":"614.126381ms","remote":"127.0.0.1:46158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-08-14T09:36:22.643268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.775759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-14T09:36:22.643401Z","caller":"traceutil/trace.go:171","msg":"trace[893114717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"452.944453ms","start":"2026-08-14T09:36:22.190393Z","end":"2026-08-14T09:36:22.643338Z","steps":["trace[893114717] 'agreement among raft nodes before linearized reading' (duration: 452.256546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:22.64295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.225772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"warn","ts":"2026-08-14T09:36:22.643471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:22.190358Z","time spent":"453.102307ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:36:22.643531Z","caller":"traceutil/trace.go:171","msg":"trace[1344719214] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:604; }","duration":"215.855807ms","start":"2026-08-14T09:36:22.427631Z","end":"2026-08-14T09:36:22.643487Z","steps":["trace[1344719214] 'agreement among raft nodes before linearized reading' (duration: 214.971146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:22.644143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.61052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-14T09:36:22.644193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.566963ms","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-08-14T09:36:22.644244Z","caller":"traceutil/trace.go:171","msg":"trace[1398941790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"460.85296ms","start":"2026-08-14T09:36:22.183378Z","end":"2026-08-14T09:36:22.644231Z","steps":["trace[1398941790] 'agreement among raft nodes before linearized reading' (duration: 460.524611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:22.64428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:22.183348Z","time spent":"460.916202ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T09:36:22.64504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.353629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-14T09:36:22.645085Z","caller":"traceutil/trace.go:171","msg":"trace[149124374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:604; }","duration":"376.448322ms","start":"2026-08-14T09:36:22.268618Z","end":"2026-08-14T09:36:22.645067Z","steps":["trace[149124374] 'agreement among raft nodes before linearized reading' (duration: 376.34751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:22.64511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:22.268587Z","time spent":"376.517093ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-14T09:36:22.644251Z","caller":"traceutil/trace.go:171","msg":"trace[1635856053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"453.776274ms","start":"2026-08-14T09:36:22.190464Z","end":"2026-08-14T09:36:22.64424Z","steps":["trace[1635856053] 'agreement among raft nodes before linearized reading' (duration: 453.479495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:22.646909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:22.190431Z","time spent":"456.374468ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:36:22.645975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.630735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:36:22.647657Z","caller":"traceutil/trace.go:171","msg":"trace[1899966878] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:604; }","duration":"419.373359ms","start":"2026-08-14T09:36:22.228262Z","end":"2026-08-14T09:36:22.647636Z","steps":["trace[1899966878] 'agreement among raft nodes before linearized reading' (duration: 417.672046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:22.647733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:22.228227Z","time spent":"419.492092ms","remote":"127.0.0.1:46158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-08-14T09:36:25.812983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.394187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:36:25.813168Z","caller":"traceutil/trace.go:171","msg":"trace[1020685085] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"188.081897ms","start":"2026-08-14T09:36:25.625049Z","end":"2026-08-14T09:36:25.813131Z","steps":["trace[1020685085] 'read index received' (duration: 11.075776ms)","trace[1020685085] 'applied index is now lower than readState.Index' (duration: 177.004151ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:36:25.813475Z","caller":"traceutil/trace.go:171","msg":"trace[696262913] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"201.279276ms","start":"2026-08-14T09:36:25.612182Z","end":"2026-08-14T09:36:25.813461Z","steps":["trace[696262913] 'process raft request' (duration: 24.001868ms)","trace[696262913] 'compare' (duration: 176.040296ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:36:25.813737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.657241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:36:25.813803Z","caller":"traceutil/trace.go:171","msg":"trace[1068412719] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:636; }","duration":"188.766554ms","start":"2026-08-14T09:36:25.625022Z","end":"2026-08-14T09:36:25.813789Z","steps":["trace[1068412719] 'agreement among raft nodes before linearized reading' (duration: 188.6158ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:25.814159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.961679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:36:25.814438Z","caller":"traceutil/trace.go:171","msg":"trace[1524240277] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:636; }","duration":"189.164884ms","start":"2026-08-14T09:36:25.625077Z","end":"2026-08-14T09:36:25.814242Z","steps":["trace[1524240277] 'agreement among raft nodes before linearized reading' (duration: 188.957439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:25.814775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.897617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:36:25.814806Z","caller":"traceutil/trace.go:171","msg":"trace[2102256303] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:636; }","duration":"188.947218ms","start":"2026-08-14T09:36:25.625851Z","end":"2026-08-14T09:36:25.814799Z","steps":["trace[2102256303] 'agreement among raft nodes before linearized reading' (duration: 188.903387ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:36:26.682907Z","caller":"traceutil/trace.go:171","msg":"trace[1247208189] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"364.904464ms","start":"2026-08-14T09:36:26.317962Z","end":"2026-08-14T09:36:26.682867Z","steps":["trace[1247208189] 'process raft request' (duration: 363.979121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:26.683116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:26.317915Z","time spent":"365.092779ms","remote":"127.0.0.1:46204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036153,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:36:26.683544Z","caller":"traceutil/trace.go:171","msg":"trace[647866454] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"285.596412ms","start":"2026-08-14T09:36:26.397925Z","end":"2026-08-14T09:36:26.683521Z","steps":["trace[647866454] 'process raft request' (duration: 285.423067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:31.253484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.574107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4gw5n\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-14T09:36:31.253589Z","caller":"traceutil/trace.go:171","msg":"trace[293362542] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4gw5n; range_end:; response_count:1; response_revision:671; }","duration":"165.744941ms","start":"2026-08-14T09:36:31.087824Z","end":"2026-08-14T09:36:31.253569Z","steps":["trace[293362542] 'range keys from in-memory index tree' (duration: 165.381442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:31.25422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.146896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-14T09:36:31.254276Z","caller":"traceutil/trace.go:171","msg":"trace[135371530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:671; }","duration":"282.967757ms","start":"2026-08-14T09:36:30.971291Z","end":"2026-08-14T09:36:31.254259Z","steps":["trace[135371530] 'range keys from in-memory index tree' (duration: 281.969332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:33.223426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845428051331988,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:36:33.723588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845428051331988,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:36:34.157594Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.473749668s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:36:34.158212Z","caller":"traceutil/trace.go:171","msg":"trace[1922943773] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"1.435098166s","start":"2026-08-14T09:36:32.723081Z","end":"2026-08-14T09:36:34.158179Z","steps":["trace[1922943773] 'read index received' (duration: 1.434766578s)","trace[1922943773] 'applied index is now lower than readState.Index' (duration: 330.248µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:36:34.158604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.435500246s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-14T09:36:34.158637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.429389954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:36:34.158703Z","caller":"traceutil/trace.go:171","msg":"trace[1911782639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:674; }","duration":"1.429498397s","start":"2026-08-14T09:36:32.729181Z","end":"2026-08-14T09:36:34.158679Z","steps":["trace[1911782639] 'agreement among raft nodes before linearized reading' (duration: 1.429311572s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:36:34.158694Z","caller":"traceutil/trace.go:171","msg":"trace[1818793777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:674; }","duration":"1.435717412s","start":"2026-08-14T09:36:32.722951Z","end":"2026-08-14T09:36:34.158668Z","steps":["trace[1818793777] 'agreement among raft nodes before linearized reading' (duration: 1.435380254s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:34.1587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201027246s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-14T09:36:34.158725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.883904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:36:34.158727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.428715098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:36:34.158792Z","caller":"traceutil/trace.go:171","msg":"trace[1723735244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:674; }","duration":"1.42883392s","start":"2026-08-14T09:36:32.729942Z","end":"2026-08-14T09:36:34.158776Z","steps":["trace[1723735244] 'agreement among raft nodes before linearized reading' (duration: 1.428591704s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:34.158784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:32.722915Z","time spent":"1.435851325s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T09:36:34.158789Z","caller":"traceutil/trace.go:171","msg":"trace[236234636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"363.005087ms","start":"2026-08-14T09:36:33.795764Z","end":"2026-08-14T09:36:34.158769Z","steps":["trace[236234636] 'agreement among raft nodes before linearized reading' (duration: 362.892424ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:34.158793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270638947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:36:34.158869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:33.79573Z","time spent":"363.12537ms","remote":"127.0.0.1:46126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:36:34.158922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.442777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-14T09:36:34.158932Z","caller":"traceutil/trace.go:171","msg":"trace[1761551823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"1.270846532s","start":"2026-08-14T09:36:32.888048Z","end":"2026-08-14T09:36:34.158895Z","steps":["trace[1761551823] 'agreement among raft nodes before linearized reading' (duration: 1.270564145s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:34.15893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.429417044s","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-08-14T09:36:34.158964Z","caller":"traceutil/trace.go:171","msg":"trace[2107387644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:674; }","duration":"858.540669ms","start":"2026-08-14T09:36:33.300412Z","end":"2026-08-14T09:36:34.158952Z","steps":["trace[2107387644] 'agreement among raft nodes before linearized reading' (duration: 858.415356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:34.15899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:32.888019Z","time spent":"1.270962285s","remote":"127.0.0.1:46122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:36:34.15899Z","caller":"traceutil/trace.go:171","msg":"trace[44264047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:674; }","duration":"1.429492767s","start":"2026-08-14T09:36:32.729482Z","end":"2026-08-14T09:36:34.158975Z","steps":["trace[44264047] 'agreement among raft nodes before linearized reading' (duration: 1.429336573s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:34.159016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:33.300379Z","time spent":"858.628702ms","remote":"127.0.0.1:46412","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-08-14T09:36:34.159034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:32.729477Z","time spent":"1.429546258s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:36:34.158784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:32.729154Z","time spent":"1.42961673s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:36:34.158837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:32.729928Z","time spent":"1.428899712s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-14T09:36:34.158789Z","caller":"traceutil/trace.go:171","msg":"trace[2091980179] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:674; }","duration":"1.201131718s","start":"2026-08-14T09:36:32.957624Z","end":"2026-08-14T09:36:34.158756Z","steps":["trace[2091980179] 'agreement among raft nodes before linearized reading' (duration: 1.200876682s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:34.159385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:32.957534Z","time spent":"1.201831245s","remote":"127.0.0.1:46148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"info","ts":"2026-08-14T09:36:34.158339Z","caller":"traceutil/trace.go:171","msg":"trace[1298194276] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"1.474535768s","start":"2026-08-14T09:36:32.683701Z","end":"2026-08-14T09:36:34.158237Z","steps":["trace[1298194276] 'process raft request' (duration: 1.474287012s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:34.160107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:32.68366Z","time spent":"1.476306051s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:36:44.415965Z","caller":"traceutil/trace.go:171","msg":"trace[1081341347] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"126.457595ms","start":"2026-08-14T09:36:44.289479Z","end":"2026-08-14T09:36:44.415936Z","steps":["trace[1081341347] 'process raft request' (duration: 121.39141ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:36:44.416542Z","caller":"traceutil/trace.go:171","msg":"trace[1238600210] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"125.707806ms","start":"2026-08-14T09:36:44.290821Z","end":"2026-08-14T09:36:44.416528Z","steps":["trace[1238600210] 'process raft request' (duration: 125.059789ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:36:44.416611Z","caller":"traceutil/trace.go:171","msg":"trace[1298722614] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"125.106211ms","start":"2026-08-14T09:36:44.291472Z","end":"2026-08-14T09:36:44.416578Z","steps":["trace[1298722614] 'process raft request' (duration: 124.893895ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:36:44.417105Z","caller":"traceutil/trace.go:171","msg":"trace[1588531455] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"125.222794ms","start":"2026-08-14T09:36:44.291843Z","end":"2026-08-14T09:36:44.417066Z","steps":["trace[1588531455] 'process raft request' (duration: 124.686001ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:36:44.424345Z","caller":"traceutil/trace.go:171","msg":"trace[60943103] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"122.472405ms","start":"2026-08-14T09:36:44.301857Z","end":"2026-08-14T09:36:44.424329Z","steps":["trace[60943103] 'process raft request' (duration: 122.304801ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:36:47.548505Z","caller":"traceutil/trace.go:171","msg":"trace[1262303297] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"129.541302ms","start":"2026-08-14T09:36:47.418928Z","end":"2026-08-14T09:36:47.54847Z","steps":["trace[1262303297] 'process raft request' (duration: 123.894601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:52.84924Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845428051332300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:36:53.349654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845428051332300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:36:53.850958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845428051332300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:36:53.941723Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.716082794s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:36:53.942373Z","caller":"traceutil/trace.go:171","msg":"trace[2001583250] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"1.716883693s","start":"2026-08-14T09:36:52.225445Z","end":"2026-08-14T09:36:53.942329Z","steps":["trace[2001583250] 'process raft request' (duration: 1.716611897s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:36:53.942258Z","caller":"traceutil/trace.go:171","msg":"trace[734376087] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"1.593784283s","start":"2026-08-14T09:36:52.348444Z","end":"2026-08-14T09:36:53.942228Z","steps":["trace[734376087] 'read index received' (duration: 1.593494396s)","trace[734376087] 'applied index is now lower than readState.Index' (duration: 288.687µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:36:53.942592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:52.22537Z","time spent":"1.717080448s","remote":"127.0.0.1:46384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:36:53.942667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147740951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:36:53.94274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439631879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:36:53.942779Z","caller":"traceutil/trace.go:171","msg":"trace[703491498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"1.439711351s","start":"2026-08-14T09:36:52.503052Z","end":"2026-08-14T09:36:53.942764Z","steps":["trace[703491498] 'agreement among raft nodes before linearized reading' (duration: 1.439580218s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:36:53.94279Z","caller":"traceutil/trace.go:171","msg":"trace[1555820441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"1.147920385s","start":"2026-08-14T09:36:52.794834Z","end":"2026-08-14T09:36:53.942755Z","steps":["trace[1555820441] 'agreement among raft nodes before linearized reading' (duration: 1.14774222s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:53.942825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:52.503022Z","time spent":"1.439794593s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:36:53.94281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446729745s","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-08-14T09:36:53.94287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:52.794792Z","time spent":"1.148058378s","remote":"127.0.0.1:46122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:36:53.942886Z","caller":"traceutil/trace.go:171","msg":"trace[1675700756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"1.446820577s","start":"2026-08-14T09:36:52.496043Z","end":"2026-08-14T09:36:53.942864Z","steps":["trace[1675700756] 'agreement among raft nodes before linearized reading' (duration: 1.446642163s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:53.942868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.546938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-14T09:36:53.942935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:52.496033Z","time spent":"1.446894079s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:36:53.942989Z","caller":"traceutil/trace.go:171","msg":"trace[1426280341] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:795; }","duration":"983.750033ms","start":"2026-08-14T09:36:52.95921Z","end":"2026-08-14T09:36:53.942961Z","steps":["trace[1426280341] 'agreement among raft nodes before linearized reading' (duration: 983.337263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:53.943067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:52.959176Z","time spent":"983.876945ms","remote":"127.0.0.1:46148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"warn","ts":"2026-08-14T09:36:53.942878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.435403524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-14T09:36:53.943144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.995381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:36:53.943202Z","caller":"traceutil/trace.go:171","msg":"trace[1354125678] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:795; }","duration":"368.109363ms","start":"2026-08-14T09:36:53.575077Z","end":"2026-08-14T09:36:53.943186Z","steps":["trace[1354125678] 'agreement among raft nodes before linearized reading' (duration: 368.023481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:53.943239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:53.575032Z","time spent":"368.199655ms","remote":"127.0.0.1:46406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:36:53.942605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456759685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T09:36:53.943309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455557904s","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-08-14T09:36:53.943317Z","caller":"traceutil/trace.go:171","msg":"trace[1707959231] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:795; }","duration":"1.457576625s","start":"2026-08-14T09:36:52.485727Z","end":"2026-08-14T09:36:53.943304Z","steps":["trace[1707959231] 'agreement among raft nodes before linearized reading' (duration: 1.456781546s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:36:53.943362Z","caller":"traceutil/trace.go:171","msg":"trace[1967018748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:795; }","duration":"1.455667647s","start":"2026-08-14T09:36:52.487682Z","end":"2026-08-14T09:36:53.94335Z","steps":["trace[1967018748] 'agreement among raft nodes before linearized reading' (duration: 1.455531224s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:53.943402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:52.487651Z","time spent":"1.455743318s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T09:36:53.943458Z","caller":"traceutil/trace.go:171","msg":"trace[588442229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"1.435813593s","start":"2026-08-14T09:36:52.507363Z","end":"2026-08-14T09:36:53.943176Z","steps":["trace[588442229] 'agreement among raft nodes before linearized reading' (duration: 1.435179008s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:53.943673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:52.507351Z","time spent":"1.436300366s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:36:53.943762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.59532236s","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-08-14T09:36:53.945028Z","caller":"traceutil/trace.go:171","msg":"trace[1212257764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"1.596619473s","start":"2026-08-14T09:36:52.348368Z","end":"2026-08-14T09:36:53.944988Z","steps":["trace[1212257764] 'agreement among raft nodes before linearized reading' (duration: 1.594055319s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:36:53.945163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:52.348333Z","time spent":"1.596771256s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:36:53.943389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:36:52.485694Z","time spent":"1.457679147s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-08-14T09:36:54.053688Z","caller":"traceutil/trace.go:171","msg":"trace[160558799] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"104.901119ms","start":"2026-08-14T09:36:53.948757Z","end":"2026-08-14T09:36:54.053658Z","steps":["trace[160558799] 'process raft request' (duration: 84.100541ms)","trace[160558799] 'compare' (duration: 20.374108ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:36:54.053768Z","caller":"traceutil/trace.go:171","msg":"trace[634207297] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"103.582985ms","start":"2026-08-14T09:36:53.950175Z","end":"2026-08-14T09:36:54.053758Z","steps":["trace[634207297] 'process raft request' (duration: 103.331879ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:36:54.053694Z","caller":"traceutil/trace.go:171","msg":"trace[321430885] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"103.516773ms","start":"2026-08-14T09:36:53.950159Z","end":"2026-08-14T09:36:54.053676Z","steps":["trace[321430885] 'process raft request' (duration: 103.305788ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:36:54.054523Z","caller":"traceutil/trace.go:171","msg":"trace[1886000860] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"105.307419ms","start":"2026-08-14T09:36:53.948825Z","end":"2026-08-14T09:36:54.054133Z","steps":["trace[1886000860] 'process raft request' (duration: 104.596551ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:36:54.05421Z","caller":"traceutil/trace.go:171","msg":"trace[1953176763] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"103.255557ms","start":"2026-08-14T09:36:53.950368Z","end":"2026-08-14T09:36:54.053623Z","steps":["trace[1953176763] 'process raft request' (duration: 103.183375ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:37:07.650147Z","caller":"traceutil/trace.go:171","msg":"trace[1043235056] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"155.601009ms","start":"2026-08-14T09:37:07.494515Z","end":"2026-08-14T09:37:07.650116Z","steps":["trace[1043235056] 'process raft request' (duration: 155.406624ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:37:08.349692Z","caller":"traceutil/trace.go:171","msg":"trace[1537393760] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"124.28503ms","start":"2026-08-14T09:37:08.22538Z","end":"2026-08-14T09:37:08.349665Z","steps":["trace[1537393760] 'process raft request' (duration: 124.217918ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:37:08.34971Z","caller":"traceutil/trace.go:171","msg":"trace[546833964] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"124.379744ms","start":"2026-08-14T09:37:08.225284Z","end":"2026-08-14T09:37:08.349664Z","steps":["trace[546833964] 'process raft request' (duration: 118.085027ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:37:08.349901Z","caller":"traceutil/trace.go:171","msg":"trace[831913215] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"124.484955ms","start":"2026-08-14T09:37:08.2254Z","end":"2026-08-14T09:37:08.349885Z","steps":["trace[831913215] 'process raft request' (duration: 124.229499ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:37:08.356337Z","caller":"traceutil/trace.go:171","msg":"trace[1300287389] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"130.858383ms","start":"2026-08-14T09:37:08.22546Z","end":"2026-08-14T09:37:08.356319Z","steps":["trace[1300287389] 'process raft request' (duration: 124.363441ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:37:45.847637Z","caller":"traceutil/trace.go:171","msg":"trace[959777056] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"192.315652ms","start":"2026-08-14T09:37:45.65529Z","end":"2026-08-14T09:37:45.847605Z","steps":["trace[959777056] 'process raft request' (duration: 191.715657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:37:47.068826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845428051332909,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:37:47.27322Z","caller":"traceutil/trace.go:171","msg":"trace[2025716312] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"738.086902ms","start":"2026-08-14T09:37:46.534766Z","end":"2026-08-14T09:37:47.272853Z","steps":["trace[2025716312] 'process raft request' (duration: 737.825396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:37:47.273404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:37:46.534745Z","time spent":"738.554954ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:37:47.343375Z","caller":"traceutil/trace.go:171","msg":"trace[1985129392] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"771.945555ms","start":"2026-08-14T09:37:46.571399Z","end":"2026-08-14T09:37:47.343344Z","steps":["trace[1985129392] 'process raft request' (duration: 742.676588ms)","trace[1985129392] 'compare' (duration: 29.031421ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:37:47.343605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:37:46.571378Z","time spent":"772.100378ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:37:47.343742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.907308ms","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-08-14T09:37:47.343853Z","caller":"traceutil/trace.go:171","msg":"trace[1787217819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1069; }","duration":"775.088664ms","start":"2026-08-14T09:37:46.568738Z","end":"2026-08-14T09:37:47.343827Z","steps":["trace[1787217819] 'agreement among raft nodes before linearized reading' (duration: 774.701864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:37:47.34393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:37:46.568721Z","time spent":"775.198636ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T09:37:47.343988Z","caller":"traceutil/trace.go:171","msg":"trace[1034264267] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1111; }","duration":"774.488148ms","start":"2026-08-14T09:37:46.568781Z","end":"2026-08-14T09:37:47.343269Z","steps":["trace[1034264267] 'read index received' (duration: 703.865792ms)","trace[1034264267] 'applied index is now lower than readState.Index' (duration: 70.620996ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:37:47.344589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.966227ms","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-08-14T09:37:47.344642Z","caller":"traceutil/trace.go:171","msg":"trace[583533527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1069; }","duration":"760.04272ms","start":"2026-08-14T09:37:46.584583Z","end":"2026-08-14T09:37:47.344626Z","steps":["trace[583533527] 'agreement among raft nodes before linearized reading' (duration: 759.849765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:37:47.344684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:37:46.584572Z","time spent":"760.103582ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:37:47.344821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.556289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:37:47.344857Z","caller":"traceutil/trace.go:171","msg":"trace[1138725664] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1069; }","duration":"192.659742ms","start":"2026-08-14T09:37:47.152187Z","end":"2026-08-14T09:37:47.344847Z","steps":["trace[1138725664] 'agreement among raft nodes before linearized reading' (duration: 192.60138ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:37:47.345212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.631711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:37:47.34533Z","caller":"traceutil/trace.go:171","msg":"trace[1756123858] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1069; }","duration":"589.805735ms","start":"2026-08-14T09:37:46.755505Z","end":"2026-08-14T09:37:47.34531Z","steps":["trace[1756123858] 'agreement among raft nodes before linearized reading' (duration: 589.648221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:37:47.345376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:37:46.755472Z","time spent":"589.891089ms","remote":"127.0.0.1:46290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:37:47.345618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.650865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:37:47.345656Z","caller":"traceutil/trace.go:171","msg":"trace[1279559483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1069; }","duration":"748.712187ms","start":"2026-08-14T09:37:46.596935Z","end":"2026-08-14T09:37:47.345647Z","steps":["trace[1279559483] 'agreement among raft nodes before linearized reading' (duration: 748.602665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:37:47.345691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:37:46.596926Z","time spent":"748.759138ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:37:47.34631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.349536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:37:47.347406Z","caller":"traceutil/trace.go:171","msg":"trace[1017959684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1069; }","duration":"761.508266ms","start":"2026-08-14T09:37:46.58588Z","end":"2026-08-14T09:37:47.347388Z","steps":["trace[1017959684] 'agreement among raft nodes before linearized reading' (duration: 760.190473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:37:47.347453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:37:46.585871Z","time spent":"761.567257ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:37:47.50899Z","caller":"traceutil/trace.go:171","msg":"trace[1821009978] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"156.346457ms","start":"2026-08-14T09:37:47.352584Z","end":"2026-08-14T09:37:47.508931Z","steps":["trace[1821009978] 'process raft request' (duration: 156.263135ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:37:47.509049Z","caller":"traceutil/trace.go:171","msg":"trace[1353459786] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"156.701025ms","start":"2026-08-14T09:37:47.352311Z","end":"2026-08-14T09:37:47.509012Z","steps":["trace[1353459786] 'process raft request' (duration: 97.760669ms)","trace[1353459786] 'compare' (duration: 58.559226ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:37:48.803823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.169186ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-14T09:37:49.016822Z","caller":"traceutil/trace.go:171","msg":"trace[1383292984] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"137.147621ms","start":"2026-08-14T09:37:48.879636Z","end":"2026-08-14T09:37:49.016784Z","steps":["trace[1383292984] 'process raft request' (duration: 136.924425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:37:49.381658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.781907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-14T09:37:49.381794Z","caller":"traceutil/trace.go:171","msg":"trace[94372297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1074; }","duration":"102.983421ms","start":"2026-08-14T09:37:49.278782Z","end":"2026-08-14T09:37:49.381765Z","steps":["trace[94372297] 'range keys from in-memory index tree' (duration: 102.562421ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:37:49.579466Z","caller":"traceutil/trace.go:171","msg":"trace[624138481] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1120; }","duration":"146.991466ms","start":"2026-08-14T09:37:49.432432Z","end":"2026-08-14T09:37:49.579424Z","steps":["trace[624138481] 'read index received' (duration: 12.646545ms)","trace[624138481] 'applied index is now lower than readState.Index' (duration: 134.343741ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:37:49.579584Z","caller":"traceutil/trace.go:171","msg":"trace[1490830256] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"190.209381ms","start":"2026-08-14T09:37:49.389327Z","end":"2026-08-14T09:37:49.579537Z","steps":["trace[1490830256] 'process raft request' (duration: 188.517668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:37:49.580052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.591411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:37:49.58011Z","caller":"traceutil/trace.go:171","msg":"trace[915290025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"147.741134ms","start":"2026-08-14T09:37:49.432354Z","end":"2026-08-14T09:37:49.580095Z","steps":["trace[915290025] 'agreement among raft nodes before linearized reading' (duration: 147.281273ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:37:49.85294Z","caller":"traceutil/trace.go:171","msg":"trace[326519782] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"267.478601ms","start":"2026-08-14T09:37:49.585424Z","end":"2026-08-14T09:37:49.852903Z","steps":["trace[326519782] 'process raft request' (duration: 267.362448ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:37:49.853215Z","caller":"traceutil/trace.go:171","msg":"trace[1843488250] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"267.249305ms","start":"2026-08-14T09:37:49.585925Z","end":"2026-08-14T09:37:49.853174Z","steps":["trace[1843488250] 'process raft request' (duration: 266.933758ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:37:49.853338Z","caller":"traceutil/trace.go:171","msg":"trace[1191469521] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"268.099426ms","start":"2026-08-14T09:37:49.585225Z","end":"2026-08-14T09:37:49.853324Z","steps":["trace[1191469521] 'process raft request' (duration: 251.440532ms)","trace[1191469521] 'compare' (duration: 15.773422ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:38:05.654445Z","caller":"traceutil/trace.go:171","msg":"trace[1377361353] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1227; }","duration":"132.431293ms","start":"2026-08-14T09:38:05.52199Z","end":"2026-08-14T09:38:05.654421Z","steps":["trace[1377361353] 'read index received' (duration: 132.147486ms)","trace[1377361353] 'applied index is now lower than readState.Index' (duration: 283.047µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:38:05.654701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.689469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-14T09:38:05.654745Z","caller":"traceutil/trace.go:171","msg":"trace[345155312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1178; }","duration":"132.798872ms","start":"2026-08-14T09:38:05.521933Z","end":"2026-08-14T09:38:05.654732Z","steps":["trace[345155312] 'agreement among raft nodes before linearized reading' (duration: 132.584727ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:38:05.655048Z","caller":"traceutil/trace.go:171","msg":"trace[1506953880] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"211.986031ms","start":"2026-08-14T09:38:05.443053Z","end":"2026-08-14T09:38:05.655039Z","steps":["trace[1506953880] 'process raft request' (duration: 211.181472ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:06.296905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845428051333153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:38:06.798061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845428051333153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:38:06.988422Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.328947656s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:38:07.025786Z","caller":"traceutil/trace.go:171","msg":"trace[1971186442] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"1.366401716s","start":"2026-08-14T09:38:05.659345Z","end":"2026-08-14T09:38:07.025746Z","steps":["trace[1971186442] 'process raft request' (duration: 1.32951833s)","trace[1971186442] 'compare' (duration: 36.717832ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:38:07.02601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:05.659285Z","time spent":"1.366598251s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:38:07.797301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000579977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-14T09:38:07.797543Z","caller":"traceutil/trace.go:171","msg":"trace[1091977500] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000917785s","start":"2026-08-14T09:38:05.796594Z","end":"2026-08-14T09:38:07.797512Z","steps":["trace[1091977500] 'agreement among raft nodes before linearized reading' (duration: 2.000615448s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:07.797627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:05.796566Z","time spent":"2.001044918s","remote":"127.0.0.1:46122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/14 09:38:07 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-14T09:38:08.398215Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.409485069s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-14T09:38:08.405198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:07.264608Z","time spent":"1.140580712s","remote":"127.0.0.1:60396","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-14T09:38:08.405751Z","caller":"traceutil/trace.go:171","msg":"trace[1099509789] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"2.744092936s","start":"2026-08-14T09:38:05.661633Z","end":"2026-08-14T09:38:08.405726Z","steps":["trace[1099509789] 'process raft request' (duration: 2.736896167s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:08.405886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:05.661595Z","time spent":"2.744205089s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:38:08.405931Z","caller":"traceutil/trace.go:171","msg":"trace[1235087984] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1228; }","duration":"2.609248143s","start":"2026-08-14T09:38:05.796652Z","end":"2026-08-14T09:38:08.4059Z","steps":["trace[1235087984] 'read index received' (duration: 1.192223866s)","trace[1235087984] 'applied index is now lower than readState.Index' (duration: 1.417022327s)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:38:08.40599Z","caller":"traceutil/trace.go:171","msg":"trace[1916172252] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"744.870943ms","start":"2026-08-14T09:38:07.661091Z","end":"2026-08-14T09:38:08.405962Z","steps":["trace[1916172252] 'process raft request' (duration: 744.624617ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:38:08.406021Z","caller":"traceutil/trace.go:171","msg":"trace[1649759733] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"1.952318188s","start":"2026-08-14T09:38:06.453682Z","end":"2026-08-14T09:38:08.406Z","steps":["trace[1649759733] 'process raft request' (duration: 1.95198179s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:08.406234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:06.453642Z","time spent":"1.952552333s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:38:08.406325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:07.661049Z","time spent":"745.055308ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:38:08.407789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.468200576s","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-08-14T09:38:08.407825Z","caller":"traceutil/trace.go:171","msg":"trace[200472653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1182; }","duration":"2.468291567s","start":"2026-08-14T09:38:05.939525Z","end":"2026-08-14T09:38:08.407816Z","steps":["trace[200472653] 'agreement among raft nodes before linearized reading' (duration: 2.468162524s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:08.407853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:05.939489Z","time spent":"2.468358579s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:38:08.40786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.735532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:38:08.407906Z","caller":"traceutil/trace.go:171","msg":"trace[1642934913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"609.824925ms","start":"2026-08-14T09:38:07.798067Z","end":"2026-08-14T09:38:08.407892Z","steps":["trace[1642934913] 'agreement among raft nodes before linearized reading' (duration: 609.753944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:08.407943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:07.798039Z","time spent":"609.898376ms","remote":"127.0.0.1:46126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:38:08.407967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.463604232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:38:08.408013Z","caller":"traceutil/trace.go:171","msg":"trace[634402318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1182; }","duration":"2.463707275s","start":"2026-08-14T09:38:05.944294Z","end":"2026-08-14T09:38:08.408001Z","steps":["trace[634402318] 'agreement among raft nodes before linearized reading' (duration: 2.4635059s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:08.408003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.16904ms","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-08-14T09:38:08.408041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:05.944262Z","time spent":"2.463771406s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-14T09:38:08.408068Z","caller":"traceutil/trace.go:171","msg":"trace[1902028349] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1182; }","duration":"330.423386ms","start":"2026-08-14T09:38:08.077629Z","end":"2026-08-14T09:38:08.408052Z","steps":["trace[1902028349] 'agreement among raft nodes before linearized reading' (duration: 330.139509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:08.40811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:08.077583Z","time spent":"330.515539ms","remote":"127.0.0.1:37688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:38:08.408433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.464000632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:38:08.408508Z","caller":"traceutil/trace.go:171","msg":"trace[813018407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1182; }","duration":"2.464107834s","start":"2026-08-14T09:38:05.944389Z","end":"2026-08-14T09:38:08.408497Z","steps":["trace[813018407] 'agreement among raft nodes before linearized reading' (duration: 2.46394459s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:08.408545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:05.944377Z","time spent":"2.464160415s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T09:38:09.348659Z","caller":"traceutil/trace.go:171","msg":"trace[224691751] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"151.275952ms","start":"2026-08-14T09:38:09.197342Z","end":"2026-08-14T09:38:09.348618Z","steps":["trace[224691751] 'process raft request' (duration: 96.164351ms)","trace[224691751] 'compare' (duration: 54.784132ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:38:12.65319Z","caller":"traceutil/trace.go:171","msg":"trace[87529958] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"188.515018ms","start":"2026-08-14T09:38:12.464639Z","end":"2026-08-14T09:38:12.653154Z","steps":["trace[87529958] 'process raft request' (duration: 188.247441ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:38:12.653185Z","caller":"traceutil/trace.go:171","msg":"trace[1873611848] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1247; }","duration":"139.135041ms","start":"2026-08-14T09:38:12.514018Z","end":"2026-08-14T09:38:12.653153Z","steps":["trace[1873611848] 'read index received' (duration: 138.794243ms)","trace[1873611848] 'applied index is now lower than readState.Index' (duration: 339.768µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:38:12.65328Z","caller":"traceutil/trace.go:171","msg":"trace[1014931734] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"188.480647ms","start":"2026-08-14T09:38:12.464749Z","end":"2026-08-14T09:38:12.653229Z","steps":["trace[1014931734] 'process raft request' (duration: 188.364494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:12.653383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.309195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:38:12.653555Z","caller":"traceutil/trace.go:171","msg":"trace[1860446197] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1199; }","duration":"139.563281ms","start":"2026-08-14T09:38:12.513964Z","end":"2026-08-14T09:38:12.653527Z","steps":["trace[1860446197] 'agreement among raft nodes before linearized reading' (duration: 139.319055ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:38:12.653859Z","caller":"traceutil/trace.go:171","msg":"trace[689649827] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"189.118663ms","start":"2026-08-14T09:38:12.464711Z","end":"2026-08-14T09:38:12.65383Z","steps":["trace[689649827] 'process raft request' (duration: 188.360684ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:38:28.648475Z","caller":"traceutil/trace.go:171","msg":"trace[1824001376] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"117.00646ms","start":"2026-08-14T09:38:28.531448Z","end":"2026-08-14T09:38:28.648455Z","steps":["trace[1824001376] 'process raft request' (duration: 116.227261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:39.011528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845428051333498,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:38:39.378325Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.018052536s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-14T09:38:39.573274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.651948ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-14T09:38:39.573715Z","caller":"traceutil/trace.go:171","msg":"trace[336967906] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"885.7282ms","start":"2026-08-14T09:38:38.687962Z","end":"2026-08-14T09:38:39.57369Z","steps":["trace[336967906] 'process raft request' (duration: 885.427233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:39.573878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:38.687934Z","time spent":"885.850564ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:38:39.629373Z","caller":"traceutil/trace.go:171","msg":"trace[2080730018] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1400; }","duration":"1.118016846s","start":"2026-08-14T09:38:38.511314Z","end":"2026-08-14T09:38:39.629331Z","steps":["trace[2080730018] 'read index received' (duration: 867.272354ms)","trace[2080730018] 'applied index is now lower than readState.Index' (duration: 250.736942ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:38:39.629487Z","caller":"traceutil/trace.go:171","msg":"trace[578350506] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"653.745073ms","start":"2026-08-14T09:38:38.975698Z","end":"2026-08-14T09:38:39.629443Z","steps":["trace[578350506] 'process raft request' (duration: 597.976177ms)","trace[578350506] 'compare' (duration: 55.468359ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:38:39.629673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118326074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T09:38:39.629694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:38.975672Z","time spent":"653.916947ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:38:39.629753Z","caller":"traceutil/trace.go:171","msg":"trace[80425059] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1346; }","duration":"1.118483508s","start":"2026-08-14T09:38:38.511252Z","end":"2026-08-14T09:38:39.629736Z","steps":["trace[80425059] 'agreement among raft nodes before linearized reading' (duration: 1.118289983s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:39.629805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:38.511223Z","time spent":"1.118571081s","remote":"127.0.0.1:46474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:38:39.629964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.1146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-08-14T09:38:39.630048Z","caller":"traceutil/trace.go:171","msg":"trace[1614997226] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1346; }","duration":"932.251034ms","start":"2026-08-14T09:38:38.69778Z","end":"2026-08-14T09:38:39.630031Z","steps":["trace[1614997226] 'agreement among raft nodes before linearized reading' (duration: 931.934186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:39.630088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:38.697759Z","time spent":"932.322746ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-14T09:38:39.630538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.815707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:38:39.630575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.387296ms","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":"warn","ts":"2026-08-14T09:38:39.630583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.668088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:38:39.63061Z","caller":"traceutil/trace.go:171","msg":"trace[1382272606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1346; }","duration":"834.907589ms","start":"2026-08-14T09:38:38.795677Z","end":"2026-08-14T09:38:39.630585Z","steps":["trace[1382272606] 'agreement among raft nodes before linearized reading' (duration: 834.823197ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:38:39.630628Z","caller":"traceutil/trace.go:171","msg":"trace[1447073081] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1346; }","duration":"527.488329ms","start":"2026-08-14T09:38:39.103117Z","end":"2026-08-14T09:38:39.630606Z","steps":["trace[1447073081] 'agreement among raft nodes before linearized reading' (duration: 527.423497ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:38:39.630652Z","caller":"traceutil/trace.go:171","msg":"trace[1180772581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1346; }","duration":"929.756691ms","start":"2026-08-14T09:38:38.700876Z","end":"2026-08-14T09:38:39.630633Z","steps":["trace[1180772581] 'agreement among raft nodes before linearized reading' (duration: 929.627367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:39.630685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:39.103087Z","time spent":"527.588531ms","remote":"127.0.0.1:38298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:38:39.630679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:38.79566Z","time spent":"835.005011ms","remote":"127.0.0.1:46122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T09:38:39.630704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:38.700864Z","time spent":"929.828462ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:38:39.630408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.396408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-14T09:38:39.630453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.395559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:38:39.630883Z","caller":"traceutil/trace.go:171","msg":"trace[1746547142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1346; }","duration":"437.912531ms","start":"2026-08-14T09:38:39.192961Z","end":"2026-08-14T09:38:39.630874Z","steps":["trace[1746547142] 'agreement among raft nodes before linearized reading' (duration: 437.375447ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:38:39.630894Z","caller":"traceutil/trace.go:171","msg":"trace[172882902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1346; }","duration":"798.85382ms","start":"2026-08-14T09:38:38.832028Z","end":"2026-08-14T09:38:39.630882Z","steps":["trace[172882902] 'agreement among raft nodes before linearized reading' (duration: 798.324747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:39.630942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:38.832015Z","time spent":"798.915972ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:38:39.630317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.411735ms","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-08-14T09:38:39.6311Z","caller":"traceutil/trace.go:171","msg":"trace[1692772781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1346; }","duration":"823.243886ms","start":"2026-08-14T09:38:38.807836Z","end":"2026-08-14T09:38:39.63108Z","steps":["trace[1692772781] 'agreement among raft nodes before linearized reading' (duration: 822.301312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:39.631175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:38.807826Z","time spent":"823.325377ms","remote":"127.0.0.1:46412","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-08-14T09:38:39.630908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:39.19294Z","time spent":"437.961131ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:38:39.630009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.199318ms","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-08-14T09:38:39.63256Z","caller":"traceutil/trace.go:171","msg":"trace[573977341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1346; }","duration":"797.785724ms","start":"2026-08-14T09:38:38.834743Z","end":"2026-08-14T09:38:39.632529Z","steps":["trace[573977341] 'agreement among raft nodes before linearized reading' (duration: 794.990213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:39.632669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:38.834732Z","time spent":"797.899586ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:38:39.630515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.035971ms","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":"info","ts":"2026-08-14T09:38:39.633064Z","caller":"traceutil/trace.go:171","msg":"trace[1586330037] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1346; }","duration":"171.620187ms","start":"2026-08-14T09:38:39.46143Z","end":"2026-08-14T09:38:39.63305Z","steps":["trace[1586330037] 'agreement among raft nodes before linearized reading' (duration: 169.030861ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:38:42.112045Z","caller":"traceutil/trace.go:171","msg":"trace[641452248] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"530.968836ms","start":"2026-08-14T09:38:41.58105Z","end":"2026-08-14T09:38:42.112018Z","steps":["trace[641452248] 'process raft request' (duration: 530.750641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:42.112192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:41.581012Z","time spent":"531.0991ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:38:42.111997Z","caller":"traceutil/trace.go:171","msg":"trace[950381349] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"452.20988ms","start":"2026-08-14T09:38:41.659769Z","end":"2026-08-14T09:38:42.111978Z","steps":["trace[950381349] 'read index received' (duration: 451.980105ms)","trace[950381349] 'applied index is now lower than readState.Index' (duration: 228.715µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:38:42.112387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.60138ms","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-08-14T09:38:42.112511Z","caller":"traceutil/trace.go:171","msg":"trace[1474585583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1357; }","duration":"452.775645ms","start":"2026-08-14T09:38:41.659725Z","end":"2026-08-14T09:38:42.112501Z","steps":["trace[1474585583] 'agreement among raft nodes before linearized reading' (duration: 452.415066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:42.112587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:41.659706Z","time spent":"452.846167ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:38:42.112633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.293212ms","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-08-14T09:38:42.112714Z","caller":"traceutil/trace.go:171","msg":"trace[740429257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1357; }","duration":"427.420865ms","start":"2026-08-14T09:38:41.685274Z","end":"2026-08-14T09:38:42.112695Z","steps":["trace[740429257] 'agreement among raft nodes before linearized reading' (duration: 427.157089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:42.112716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.286272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-14T09:38:42.112754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:41.685254Z","time spent":"427.491147ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T09:38:42.112792Z","caller":"traceutil/trace.go:171","msg":"trace[667032401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1357; }","duration":"425.396424ms","start":"2026-08-14T09:38:41.687378Z","end":"2026-08-14T09:38:42.112774Z","steps":["trace[667032401] 'agreement among raft nodes before linearized reading' (duration: 425.061266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:42.112831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:41.687364Z","time spent":"425.459516ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:38:42.112669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.574252ms","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-08-14T09:38:42.112856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.61662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-14T09:38:42.112863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.423105ms","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-08-14T09:38:42.112888Z","caller":"traceutil/trace.go:171","msg":"trace[2034731892] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1357; }","duration":"249.85716ms","start":"2026-08-14T09:38:41.863014Z","end":"2026-08-14T09:38:42.112871Z","steps":["trace[2034731892] 'agreement among raft nodes before linearized reading' (duration: 249.428269ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:38:42.11295Z","caller":"traceutil/trace.go:171","msg":"trace[2113093959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1357; }","duration":"425.578299ms","start":"2026-08-14T09:38:41.687351Z","end":"2026-08-14T09:38:42.112929Z","steps":["trace[2113093959] 'agreement among raft nodes before linearized reading' (duration: 425.155739ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:38:42.112963Z","caller":"traceutil/trace.go:171","msg":"trace[1148623234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1357; }","duration":"425.767644ms","start":"2026-08-14T09:38:41.687142Z","end":"2026-08-14T09:38:42.112909Z","steps":["trace[1148623234] 'agreement among raft nodes before linearized reading' (duration: 425.439105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:42.113005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:41.68732Z","time spent":"425.674722ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:38:42.113011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:41.686917Z","time spent":"426.078172ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:38:42.113828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.686181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:38:42.11397Z","caller":"traceutil/trace.go:171","msg":"trace[1573633539] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1357; }","duration":"280.05331ms","start":"2026-08-14T09:38:41.833892Z","end":"2026-08-14T09:38:42.113945Z","steps":["trace[1573633539] 'agreement among raft nodes before linearized reading' (duration: 278.564112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:42.772955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845428051333531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:38:43.273557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646845428051333531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:38:43.274647Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.149953583s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:38:43.288806Z","caller":"traceutil/trace.go:171","msg":"trace[226392909] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"1.164250193s","start":"2026-08-14T09:38:42.124527Z","end":"2026-08-14T09:38:43.288777Z","steps":["trace[226392909] 'process raft request' (duration: 1.150299171s)","trace[226392909] 'compare' (duration: 13.766487ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:38:43.289592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:42.1245Z","time spent":"1.164376265s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:38:43.291315Z","caller":"traceutil/trace.go:171","msg":"trace[303986744] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1414; }","duration":"1.018551399s","start":"2026-08-14T09:38:42.272743Z","end":"2026-08-14T09:38:43.291294Z","steps":["trace[303986744] 'read index received' (duration: 1.002070244s)","trace[303986744] 'applied index is now lower than readState.Index' (duration: 16.479845ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:38:43.291548Z","caller":"traceutil/trace.go:171","msg":"trace[1194409256] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"1.154049255s","start":"2026-08-14T09:38:42.13747Z","end":"2026-08-14T09:38:43.291519Z","steps":["trace[1194409256] 'process raft request' (duration: 1.153781078s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:43.291635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018879287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:38:43.291671Z","caller":"traceutil/trace.go:171","msg":"trace[1432347371] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1363; }","duration":"1.01895391s","start":"2026-08-14T09:38:42.272707Z","end":"2026-08-14T09:38:43.29166Z","steps":["trace[1432347371] 'agreement among raft nodes before linearized reading' (duration: 1.018888498s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:43.291693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:42.272688Z","time spent":"1.01899891s","remote":"127.0.0.1:37762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:38:43.291688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:42.137459Z","time spent":"1.154146507s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:38:43.291786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.696892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:38:43.291814Z","caller":"traceutil/trace.go:171","msg":"trace[1489833164] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"1.166687723s","start":"2026-08-14T09:38:42.125074Z","end":"2026-08-14T09:38:43.291761Z","steps":["trace[1489833164] 'process raft request' (duration: 1.166098578s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:38:43.291851Z","caller":"traceutil/trace.go:171","msg":"trace[1652209835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1363; }","duration":"496.801225ms","start":"2026-08-14T09:38:42.795031Z","end":"2026-08-14T09:38:43.291832Z","steps":["trace[1652209835] 'agreement among raft nodes before linearized reading' (duration: 496.710303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:43.291934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:42.795009Z","time spent":"496.910647ms","remote":"127.0.0.1:46122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:38:43.291957Z","caller":"traceutil/trace.go:171","msg":"trace[1571611386] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"1.155537122s","start":"2026-08-14T09:38:42.136411Z","end":"2026-08-14T09:38:43.291948Z","steps":["trace[1571611386] 'process raft request' (duration: 1.154807104s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:38:43.291959Z","caller":"traceutil/trace.go:171","msg":"trace[638889862] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"1.156396885s","start":"2026-08-14T09:38:42.135547Z","end":"2026-08-14T09:38:43.291944Z","steps":["trace[638889862] 'process raft request' (duration: 1.155650696s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:43.292004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:42.136388Z","time spent":"1.155590054s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:38:43.292031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:42.135528Z","time spent":"1.156464686s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:38:43.292104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:42.125062Z","time spent":"1.166862027s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:38:43.292158Z","caller":"traceutil/trace.go:171","msg":"trace[285868886] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"1.167234437s","start":"2026-08-14T09:38:42.12489Z","end":"2026-08-14T09:38:43.292125Z","steps":["trace[285868886] 'process raft request' (duration: 1.166202271s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:43.292196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:42.124879Z","time spent":"1.167297058s","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:38:43.29229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.186168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-14T09:38:43.292356Z","caller":"traceutil/trace.go:171","msg":"trace[1145984047] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:1363; }","duration":"325.289521ms","start":"2026-08-14T09:38:42.967052Z","end":"2026-08-14T09:38:43.292341Z","steps":["trace[1145984047] 'agreement among raft nodes before linearized reading' (duration: 325.101856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:43.292425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:42.96703Z","time spent":"325.366943ms","remote":"127.0.0.1:46148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"warn","ts":"2026-08-14T09:38:43.292494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.556862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:38:43.29252Z","caller":"traceutil/trace.go:171","msg":"trace[1746248393] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1363; }","duration":"894.610484ms","start":"2026-08-14T09:38:42.397902Z","end":"2026-08-14T09:38:43.292513Z","steps":["trace[1746248393] 'agreement among raft nodes before linearized reading' (duration: 894.567013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:43.292544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:42.397887Z","time spent":"894.647735ms","remote":"127.0.0.1:46478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:38:43.293006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.307161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-08-14T09:38:43.293062Z","caller":"traceutil/trace.go:171","msg":"trace[1369192942] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1363; }","duration":"960.399743ms","start":"2026-08-14T09:38:42.332651Z","end":"2026-08-14T09:38:43.293051Z","steps":["trace[1369192942] 'agreement among raft nodes before linearized reading' (duration: 959.027588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:38:43.293085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:38:42.332628Z","time spent":"960.451564ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "}