{"level":"warn","ts":"2026-04-13T14:18:22.489422Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T14:18:22.489553Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-04-13T14:18:22.490499Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T14:18:22.490515Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-13T14:18:22.49054Z","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-04-13T14:18:22.4914Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-13T14:18:22.491554Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-04-13T14:18:22.497782Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.490106ms"} {"level":"info","ts":"2026-04-13T14:18:22.507636Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-13T14:18:22.507746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T14:18:22.507772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-13T14:18:22.507783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T14:18:22.507796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-13T14:18:22.507845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-13T14:18:22.514638Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T14:18:22.51627Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T14:18:22.5172Z","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-04-13T14:18:22.519649Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T14:18:22.519794Z","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-04-13T14:18:22.519802Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T14:18:22.519916Z","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-04-13T14:18:22.51994Z","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-04-13T14:18:22.521333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-13T14:18:22.521712Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-13T14:18:22.524603Z","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-04-13T14:18:22.524675Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-13T14:18:22.524707Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-13T14:18:22.524891Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T14:18:22.524921Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T14:18:22.809282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T14:18:22.809329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T14:18:22.809359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-13T14:18:22.809375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-13T14:18:22.809382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-13T14:18:22.809394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-13T14:18:22.809497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-13T14:18:22.810876Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T14:18:22.810888Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:18:22.813154Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:18:22.813187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T14:18:22.813742Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:18:22.813829Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T14:18:22.813856Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T14:18:22.816107Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T14:18:22.816293Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T14:18:22.816352Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T14:18:22.816517Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"warn","ts":"2026-04-13T14:18:27.69247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.824809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:18:27.692607Z","caller":"traceutil/trace.go:171","msg":"trace[593950322] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:238; }","duration":"406.836432ms","start":"2026-04-13T14:18:27.285756Z","end":"2026-04-13T14:18:27.692592Z","steps":["trace[593950322] 'read index received' (duration: 195.277126ms)","trace[593950322] 'applied index is now lower than readState.Index' (duration: 211.558116ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:18:27.692708Z","caller":"traceutil/trace.go:171","msg":"trace[1349168703] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"418.91561ms","start":"2026-04-13T14:18:27.273737Z","end":"2026-04-13T14:18:27.692652Z","steps":["trace[1349168703] 'process raft request' (duration: 207.350893ms)","trace[1349168703] 'compare' (duration: 210.697776ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:18:27.692755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.999317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:18:27.692794Z","caller":"traceutil/trace.go:171","msg":"trace[1202851200] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:233; }","duration":"407.084789ms","start":"2026-04-13T14:18:27.2857Z","end":"2026-04-13T14:18:27.692784Z","steps":["trace[1202851200] 'agreement among raft nodes before linearized reading' (duration: 407.001636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:18:27.692843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:18:27.285678Z","time spent":"407.155961ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" "} {"level":"warn","ts":"2026-04-13T14:18:27.692874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:18:27.273709Z","time spent":"419.085425ms","remote":"127.0.0.1:32926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1944,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:18:28.54312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.315751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:18:28.543248Z","caller":"traceutil/trace.go:171","msg":"trace[182689740] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"844.369721ms","start":"2026-04-13T14:18:27.698855Z","end":"2026-04-13T14:18:28.543224Z","steps":["trace[182689740] 'process raft request' (duration: 587.874527ms)","trace[182689740] 'compare' (duration: 256.033483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:18:28.543336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:18:27.69884Z","time spent":"844.462884ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:18:28.550495Z","caller":"traceutil/trace.go:171","msg":"trace[1257771980] linearizableReadLoop","detail":"{readStateIndex:242; appliedIndex:240; }","duration":"296.326407ms","start":"2026-04-13T14:18:28.254139Z","end":"2026-04-13T14:18:28.550466Z","steps":["trace[1257771980] 'read index received' (duration: 32.547663ms)","trace[1257771980] 'applied index is now lower than readState.Index' (duration: 263.777764ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:18:28.550607Z","caller":"traceutil/trace.go:171","msg":"trace[1478755739] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"818.901944ms","start":"2026-04-13T14:18:27.731695Z","end":"2026-04-13T14:18:28.550597Z","steps":["trace[1478755739] 'process raft request' (duration: 818.647478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:18:28.550705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:18:27.731673Z","time spent":"818.960796ms","remote":"127.0.0.1:33178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":481,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T14:18:28.550752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.596985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:18:28.550821Z","caller":"traceutil/trace.go:171","msg":"trace[1162632591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:236; }","duration":"296.698706ms","start":"2026-04-13T14:18:28.254103Z","end":"2026-04-13T14:18:28.550802Z","steps":["trace[1162632591] 'agreement among raft nodes before linearized reading' (duration: 296.491991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:18:28.564431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.576985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:18:28.564498Z","caller":"traceutil/trace.go:171","msg":"trace[468359500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:237; }","duration":"264.676478ms","start":"2026-04-13T14:18:28.2998Z","end":"2026-04-13T14:18:28.564477Z","steps":["trace[468359500] 'agreement among raft nodes before linearized reading' (duration: 264.576345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:18:28.564748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.317271ms","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-04-13T14:18:28.564807Z","caller":"traceutil/trace.go:171","msg":"trace[2101898095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:237; }","duration":"168.421154ms","start":"2026-04-13T14:18:28.396372Z","end":"2026-04-13T14:18:28.564793Z","steps":["trace[2101898095] 'agreement among raft nodes before linearized reading' (duration: 168.28361ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:18:54.092651Z","caller":"traceutil/trace.go:171","msg":"trace[1467550358] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"177.382034ms","start":"2026-04-13T14:18:53.915251Z","end":"2026-04-13T14:18:54.092633Z","steps":["trace[1467550358] 'process raft request' (duration: 170.139636ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:19:00.060709Z","caller":"traceutil/trace.go:171","msg":"trace[2028393243] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"440.669027ms","start":"2026-04-13T14:18:59.620007Z","end":"2026-04-13T14:19:00.060676Z","steps":["trace[2028393243] 'process raft request' (duration: 440.462012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:00.061061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:18:59.619968Z","time spent":"440.837161ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:19:00.067493Z","caller":"traceutil/trace.go:171","msg":"trace[1650577481] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"391.760541ms","start":"2026-04-13T14:18:59.675697Z","end":"2026-04-13T14:19:00.067457Z","steps":["trace[1650577481] 'process raft request' (duration: 391.29312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:00.067719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:18:59.675664Z","time spent":"391.941596ms","remote":"127.0.0.1:32922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:19:02.196162Z","caller":"traceutil/trace.go:171","msg":"trace[1695390105] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"214.90523ms","start":"2026-04-13T14:19:01.981231Z","end":"2026-04-13T14:19:02.196136Z","steps":["trace[1695390105] 'read index received' (duration: 214.000078ms)","trace[1695390105] 'applied index is now lower than readState.Index' (duration: 904.222µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:19:02.197651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.260564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T14:19:02.197477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.373094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:19:02.198223Z","caller":"traceutil/trace.go:171","msg":"trace[1533396565] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"193.00111ms","start":"2026-04-13T14:19:02.004927Z","end":"2026-04-13T14:19:02.197928Z","steps":["trace[1533396565] 'agreement among raft nodes before linearized reading' (duration: 191.937083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:02.196721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.119835ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:19:02.198507Z","caller":"traceutil/trace.go:171","msg":"trace[734804896] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"171.836868ms","start":"2026-04-13T14:19:02.026028Z","end":"2026-04-13T14:19:02.197864Z","steps":["trace[734804896] 'agreement among raft nodes before linearized reading' (duration: 170.821202ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:19:02.198877Z","caller":"traceutil/trace.go:171","msg":"trace[2045444234] 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:581; }","duration":"217.33609ms","start":"2026-04-13T14:19:01.981215Z","end":"2026-04-13T14:19:02.198551Z","steps":["trace[2045444234] 'agreement among raft nodes before linearized reading' (duration: 215.039353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:02.199887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.626662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:19:02.200646Z","caller":"traceutil/trace.go:171","msg":"trace[168708467] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"174.049532ms","start":"2026-04-13T14:19:02.025941Z","end":"2026-04-13T14:19:02.199991Z","steps":["trace[168708467] 'agreement among raft nodes before linearized reading' (duration: 172.158276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:02.202286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.879374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:19:02.202405Z","caller":"traceutil/trace.go:171","msg":"trace[1237021296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"139.350685ms","start":"2026-04-13T14:19:02.062971Z","end":"2026-04-13T14:19:02.202322Z","steps":["trace[1237021296] 'agreement among raft nodes before linearized reading' (duration: 137.573182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:02.202695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.179324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:19:02.196219Z","caller":"traceutil/trace.go:171","msg":"trace[1411982682] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"223.353738ms","start":"2026-04-13T14:19:01.972828Z","end":"2026-04-13T14:19:02.196182Z","steps":["trace[1411982682] 'process raft request' (duration: 222.691852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:02.203014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.460084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:19:02.203132Z","caller":"traceutil/trace.go:171","msg":"trace[2082513188] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"198.637119ms","start":"2026-04-13T14:19:02.004475Z","end":"2026-04-13T14:19:02.203112Z","steps":["trace[2082513188] 'agreement among raft nodes before linearized reading' (duration: 198.048723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:02.203181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.911791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7bxmm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-13T14:19:02.203231Z","caller":"traceutil/trace.go:171","msg":"trace[2115576584] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7bxmm; range_end:; response_count:1; response_revision:581; }","duration":"140.199597ms","start":"2026-04-13T14:19:02.063019Z","end":"2026-04-13T14:19:02.203218Z","steps":["trace[2115576584] 'agreement among raft nodes before linearized reading' (duration: 133.920642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:02.202934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.3979ms","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-04-13T14:19:02.203837Z","caller":"traceutil/trace.go:171","msg":"trace[108993482] 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:581; }","duration":"222.345493ms","start":"2026-04-13T14:19:01.981458Z","end":"2026-04-13T14:19:02.203803Z","steps":["trace[108993482] 'agreement among raft nodes before linearized reading' (duration: 221.389659ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:19:02.203926Z","caller":"traceutil/trace.go:171","msg":"trace[450176731] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"176.245845ms","start":"2026-04-13T14:19:02.026485Z","end":"2026-04-13T14:19:02.202731Z","steps":["trace[450176731] 'agreement among raft nodes before linearized reading' (duration: 176.170364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:02.204126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.836041ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:19:02.204188Z","caller":"traceutil/trace.go:171","msg":"trace[1063101511] 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:581; }","duration":"221.908552ms","start":"2026-04-13T14:19:01.982261Z","end":"2026-04-13T14:19:02.20417Z","steps":["trace[1063101511] 'agreement among raft nodes before linearized reading' (duration: 220.599239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:03.022201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.542741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:19:03.022368Z","caller":"traceutil/trace.go:171","msg":"trace[328750572] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"299.24578ms","start":"2026-04-13T14:19:02.723106Z","end":"2026-04-13T14:19:03.022352Z","steps":["trace[328750572] 'read index received' (duration: 159.299158ms)","trace[328750572] 'applied index is now lower than readState.Index' (duration: 139.945452ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:19:03.022504Z","caller":"traceutil/trace.go:171","msg":"trace[319935968] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"765.008075ms","start":"2026-04-13T14:19:02.257461Z","end":"2026-04-13T14:19:03.022469Z","steps":["trace[319935968] 'process raft request' (duration: 764.838971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:03.022632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.533287ms","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-04-13T14:19:03.022662Z","caller":"traceutil/trace.go:171","msg":"trace[779959980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"299.615909ms","start":"2026-04-13T14:19:02.723036Z","end":"2026-04-13T14:19:03.022652Z","steps":["trace[779959980] 'agreement among raft nodes before linearized reading' (duration: 299.478435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:03.022689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.351749ms","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-04-13T14:19:03.022685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:19:02.25743Z","time spent":"765.132388ms","remote":"127.0.0.1:33378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T14:19:03.022722Z","caller":"traceutil/trace.go:171","msg":"trace[1391585546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"292.422371ms","start":"2026-04-13T14:19:02.73029Z","end":"2026-04-13T14:19:03.022712Z","steps":["trace[1391585546] 'agreement among raft nodes before linearized reading' (duration: 292.285268ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:19:03.022816Z","caller":"traceutil/trace.go:171","msg":"trace[1092338693] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"802.010548ms","start":"2026-04-13T14:19:02.220793Z","end":"2026-04-13T14:19:03.022804Z","steps":["trace[1092338693] 'process raft request' (duration: 279.78053ms)","trace[1092338693] 'compare' (duration: 521.391857ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:19:03.022856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.876269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-13T14:19:03.022883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.732105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-13T14:19:03.022874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:19:02.220763Z","time spent":"802.078639ms","remote":"127.0.0.1:33024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:19:03.022885Z","caller":"traceutil/trace.go:171","msg":"trace[29876799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"126.93773ms","start":"2026-04-13T14:19:02.895938Z","end":"2026-04-13T14:19:03.022875Z","steps":["trace[29876799] 'agreement among raft nodes before linearized reading' (duration: 126.847638ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:19:03.022905Z","caller":"traceutil/trace.go:171","msg":"trace[1786716604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"270.755066ms","start":"2026-04-13T14:19:02.752144Z","end":"2026-04-13T14:19:03.022899Z","steps":["trace[1786716604] 'agreement among raft nodes before linearized reading' (duration: 270.696934ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:19:07.365445Z","caller":"traceutil/trace.go:171","msg":"trace[45889377] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"126.547291ms","start":"2026-04-13T14:19:07.238867Z","end":"2026-04-13T14:19:07.365414Z","steps":["trace[45889377] 'process raft request' (duration: 126.357556ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:19:52.796184Z","caller":"traceutil/trace.go:171","msg":"trace[1051705432] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"163.575152ms","start":"2026-04-13T14:19:52.632582Z","end":"2026-04-13T14:19:52.796157Z","steps":["trace[1051705432] 'process raft request' (duration: 163.422109ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:19:53.57859Z","caller":"traceutil/trace.go:171","msg":"trace[2003661543] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"311.50439ms","start":"2026-04-13T14:19:53.267047Z","end":"2026-04-13T14:19:53.578551Z","steps":["trace[2003661543] 'process raft request' (duration: 311.313976ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:19:53.578572Z","caller":"traceutil/trace.go:171","msg":"trace[104152198] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"306.793298ms","start":"2026-04-13T14:19:53.271751Z","end":"2026-04-13T14:19:53.578545Z","steps":["trace[104152198] 'read index received' (duration: 306.568033ms)","trace[104152198] 'applied index is now lower than readState.Index' (duration: 223.965µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:19:53.578765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:19:53.267034Z","time spent":"311.637543ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:19:53.578785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.993992ms","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-04-13T14:19:53.578837Z","caller":"traceutil/trace.go:171","msg":"trace[1630526935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"307.098195ms","start":"2026-04-13T14:19:53.271722Z","end":"2026-04-13T14:19:53.57882Z","steps":["trace[1630526935] 'agreement among raft nodes before linearized reading' (duration: 306.910231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:53.578878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:19:53.271715Z","time spent":"307.151206ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T14:19:53.921446Z","caller":"traceutil/trace.go:171","msg":"trace[1974597759] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"342.672311ms","start":"2026-04-13T14:19:53.578747Z","end":"2026-04-13T14:19:53.921419Z","steps":["trace[1974597759] 'read index received' (duration: 325.718577ms)","trace[1974597759] 'applied index is now lower than readState.Index' (duration: 16.943354ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:19:53.921495Z","caller":"traceutil/trace.go:171","msg":"trace[725402223] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"605.039063ms","start":"2026-04-13T14:19:53.316419Z","end":"2026-04-13T14:19:53.921458Z","steps":["trace[725402223] 'process raft request' (duration: 588.031988ms)","trace[725402223] 'compare' (duration: 16.767299ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:19:53.921632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:19:53.316402Z","time spent":"605.154815ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:19:53.921669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.665835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T14:19:53.921705Z","caller":"traceutil/trace.go:171","msg":"trace[1551481568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"649.768746ms","start":"2026-04-13T14:19:53.271926Z","end":"2026-04-13T14:19:53.921695Z","steps":["trace[1551481568] 'agreement among raft nodes before linearized reading' (duration: 649.586232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:53.921743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:19:53.271911Z","time spent":"649.821408ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T14:19:53.921771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.843545ms","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-04-13T14:19:53.921848Z","caller":"traceutil/trace.go:171","msg":"trace[1016636537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"643.951507ms","start":"2026-04-13T14:19:53.277877Z","end":"2026-04-13T14:19:53.921829Z","steps":["trace[1016636537] 'agreement among raft nodes before linearized reading' (duration: 643.710542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:53.921895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:19:53.277867Z","time spent":"644.017499ms","remote":"127.0.0.1:33106","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-04-13T14:19:54.216613Z","caller":"traceutil/trace.go:171","msg":"trace[745076465] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"633.365967ms","start":"2026-04-13T14:19:53.58322Z","end":"2026-04-13T14:19:54.216586Z","steps":["trace[745076465] 'process raft request' (duration: 632.076175ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:19:54.216748Z","caller":"traceutil/trace.go:171","msg":"trace[877931819] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"290.652494ms","start":"2026-04-13T14:19:53.926075Z","end":"2026-04-13T14:19:54.216728Z","steps":["trace[877931819] 'process raft request' (duration: 290.46997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:19:54.2168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:19:53.583197Z","time spent":"633.494329ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:19:54.216929Z","caller":"traceutil/trace.go:171","msg":"trace[1433454005] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"290.49295ms","start":"2026-04-13T14:19:53.926422Z","end":"2026-04-13T14:19:54.216915Z","steps":["trace[1433454005] 'process raft request' (duration: 290.245925ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:19:54.216936Z","caller":"traceutil/trace.go:171","msg":"trace[437931497] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1005; }","duration":"114.749979ms","start":"2026-04-13T14:19:54.102163Z","end":"2026-04-13T14:19:54.216913Z","steps":["trace[437931497] 'read index received' (duration: 113.11478ms)","trace[437931497] 'applied index is now lower than readState.Index' (duration: 1.633999ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:19:54.217029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.852932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:19:54.217071Z","caller":"traceutil/trace.go:171","msg":"trace[2062274486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"114.926524ms","start":"2026-04-13T14:19:54.102131Z","end":"2026-04-13T14:19:54.217058Z","steps":["trace[2062274486] 'agreement among raft nodes before linearized reading' (duration: 114.852852ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:20:14.997606Z","caller":"traceutil/trace.go:171","msg":"trace[519259626] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"109.758751ms","start":"2026-04-13T14:20:14.887824Z","end":"2026-04-13T14:20:14.997583Z","steps":["trace[519259626] 'process raft request' (duration: 109.628938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:15.482724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.598592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T14:20:15.482782Z","caller":"traceutil/trace.go:171","msg":"trace[286144256] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1037; }","duration":"406.693234ms","start":"2026-04-13T14:20:15.076076Z","end":"2026-04-13T14:20:15.482769Z","steps":["trace[286144256] 'count revisions from in-memory index tree' (duration: 406.49862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:15.482814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:15.076067Z","time spent":"406.739565ms","remote":"127.0.0.1:33046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-13T14:20:15.482914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.169191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:20:15.483026Z","caller":"traceutil/trace.go:171","msg":"trace[1356621790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1037; }","duration":"419.324625ms","start":"2026-04-13T14:20:15.063676Z","end":"2026-04-13T14:20:15.483001Z","steps":["trace[1356621790] 'range keys from in-memory index tree' (duration: 419.086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:15.483093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:15.063656Z","time spent":"419.428888ms","remote":"127.0.0.1:32832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T14:20:16.371911Z","caller":"traceutil/trace.go:171","msg":"trace[911802411] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"407.135633ms","start":"2026-04-13T14:20:15.964725Z","end":"2026-04-13T14:20:16.371861Z","steps":["trace[911802411] 'read index received' (duration: 407.038371ms)","trace[911802411] 'applied index is now lower than readState.Index' (duration: 95.872µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:20:16.371935Z","caller":"traceutil/trace.go:171","msg":"trace[1714778720] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"686.613853ms","start":"2026-04-13T14:20:15.685293Z","end":"2026-04-13T14:20:16.371907Z","steps":["trace[1714778720] 'process raft request' (duration: 686.399018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:16.372232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.507824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T14:20:16.372286Z","caller":"traceutil/trace.go:171","msg":"trace[1291053085] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1038; }","duration":"407.621786ms","start":"2026-04-13T14:20:15.964651Z","end":"2026-04-13T14:20:16.372273Z","steps":["trace[1291053085] 'agreement among raft nodes before linearized reading' (duration: 407.336938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:16.372326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:15.964618Z","time spent":"407.699908ms","remote":"127.0.0.1:32862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-04-13T14:20:16.372467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:15.685268Z","time spent":"686.847177ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:20:16.380832Z","caller":"traceutil/trace.go:171","msg":"trace[1156766169] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"390.310124ms","start":"2026-04-13T14:20:15.990505Z","end":"2026-04-13T14:20:16.380815Z","steps":["trace[1156766169] 'process raft request' (duration: 390.239013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:16.380965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:15.990486Z","time spent":"390.397347ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:20:17.443169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366985281451240,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T14:20:17.527984Z","caller":"traceutil/trace.go:171","msg":"trace[1205628446] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"585.400466ms","start":"2026-04-13T14:20:16.942561Z","end":"2026-04-13T14:20:17.527961Z","steps":["trace[1205628446] 'read index received' (duration: 585.228512ms)","trace[1205628446] 'applied index is now lower than readState.Index' (duration: 170.504µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:20:17.528193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.602771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:20:17.528273Z","caller":"traceutil/trace.go:171","msg":"trace[1139725605] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1044; }","duration":"585.705243ms","start":"2026-04-13T14:20:16.94255Z","end":"2026-04-13T14:20:17.528255Z","steps":["trace[1139725605] 'agreement among raft nodes before linearized reading' (duration: 585.537749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:17.528326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:16.942488Z","time spent":"585.827496ms","remote":"127.0.0.1:60306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-13T14:20:17.528336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.313011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-13T14:20:17.528411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.747556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:20:17.528021Z","caller":"traceutil/trace.go:171","msg":"trace[1728602074] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"627.089517ms","start":"2026-04-13T14:20:16.900899Z","end":"2026-04-13T14:20:17.527988Z","steps":["trace[1728602074] 'process raft request' (duration: 626.904103ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:20:17.528414Z","caller":"traceutil/trace.go:171","msg":"trace[584090915] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1044; }","duration":"526.502606ms","start":"2026-04-13T14:20:17.001897Z","end":"2026-04-13T14:20:17.5284Z","steps":["trace[584090915] 'agreement among raft nodes before linearized reading' (duration: 526.199758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:17.528733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:17.001886Z","time spent":"526.828963ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T14:20:17.528491Z","caller":"traceutil/trace.go:171","msg":"trace[1785395762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"464.865338ms","start":"2026-04-13T14:20:17.063609Z","end":"2026-04-13T14:20:17.528474Z","steps":["trace[1785395762] 'agreement among raft nodes before linearized reading' (duration: 464.564441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:17.529007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:16.900872Z","time spent":"627.744723ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:20:17.52906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:17.063594Z","time spent":"465.436482ms","remote":"127.0.0.1:32832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T14:20:18.620662Z","caller":"traceutil/trace.go:171","msg":"trace[1758081341] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"239.21981ms","start":"2026-04-13T14:20:18.381414Z","end":"2026-04-13T14:20:18.620634Z","steps":["trace[1758081341] 'process raft request' (duration: 239.007205ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:20:18.635993Z","caller":"traceutil/trace.go:171","msg":"trace[487620707] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1088; }","duration":"193.561674ms","start":"2026-04-13T14:20:18.442407Z","end":"2026-04-13T14:20:18.635969Z","steps":["trace[487620707] 'read index received' (duration: 178.062725ms)","trace[487620707] 'applied index is now lower than readState.Index' (duration: 15.497839ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:20:18.63629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.859821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T14:20:18.636371Z","caller":"traceutil/trace.go:171","msg":"trace[235550263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1047; }","duration":"193.970864ms","start":"2026-04-13T14:20:18.44238Z","end":"2026-04-13T14:20:18.636351Z","steps":["trace[235550263] 'agreement among raft nodes before linearized reading' (duration: 193.760729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:18.6364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.972264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-13T14:20:18.636441Z","caller":"traceutil/trace.go:171","msg":"trace[2011248213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"194.060426ms","start":"2026-04-13T14:20:18.442371Z","end":"2026-04-13T14:20:18.636432Z","steps":["trace[2011248213] 'agreement among raft nodes before linearized reading' (duration: 193.908812ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:20:18.636072Z","caller":"traceutil/trace.go:171","msg":"trace[1276349424] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"250.388367ms","start":"2026-04-13T14:20:18.385653Z","end":"2026-04-13T14:20:18.636042Z","steps":["trace[1276349424] 'process raft request' (duration: 250.182102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:18.636802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.182284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:20:18.636854Z","caller":"traceutil/trace.go:171","msg":"trace[795230575] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1047; }","duration":"140.261227ms","start":"2026-04-13T14:20:18.49658Z","end":"2026-04-13T14:20:18.636841Z","steps":["trace[795230575] 'agreement among raft nodes before linearized reading' (duration: 140.101563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:18.636828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.497812ms","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-04-13T14:20:18.637029Z","caller":"traceutil/trace.go:171","msg":"trace[1014142059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1047; }","duration":"193.753909ms","start":"2026-04-13T14:20:18.443258Z","end":"2026-04-13T14:20:18.637012Z","steps":["trace[1014142059] 'agreement among raft nodes before linearized reading' (duration: 193.39817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:18.636834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.406736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:20:18.637199Z","caller":"traceutil/trace.go:171","msg":"trace[1304010324] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1047; }","duration":"144.729883ms","start":"2026-04-13T14:20:18.492398Z","end":"2026-04-13T14:20:18.637128Z","steps":["trace[1304010324] 'agreement among raft nodes before linearized reading' (duration: 144.345384ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:20:18.868575Z","caller":"traceutil/trace.go:171","msg":"trace[292873680] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"227.310978ms","start":"2026-04-13T14:20:18.641239Z","end":"2026-04-13T14:20:18.86855Z","steps":["trace[292873680] 'process raft request' (duration: 227.206795ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:20:18.868594Z","caller":"traceutil/trace.go:171","msg":"trace[1643190748] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"227.361909ms","start":"2026-04-13T14:20:18.641197Z","end":"2026-04-13T14:20:18.868559Z","steps":["trace[1643190748] 'process raft request' (duration: 203.109532ms)","trace[1643190748] 'compare' (duration: 23.894009ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:20:19.172326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.020386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:20:19.172411Z","caller":"traceutil/trace.go:171","msg":"trace[362978585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1050; }","duration":"107.174349ms","start":"2026-04-13T14:20:19.065217Z","end":"2026-04-13T14:20:19.172391Z","steps":["trace[362978585] 'range keys from in-memory index tree' (duration: 106.891982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:19.172554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.336318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:20:19.172641Z","caller":"traceutil/trace.go:171","msg":"trace[77557249] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1050; }","duration":"203.478181ms","start":"2026-04-13T14:20:18.969142Z","end":"2026-04-13T14:20:19.17262Z","steps":["trace[77557249] 'count revisions from in-memory index tree' (duration: 203.224395ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:20:31.039375Z","caller":"traceutil/trace.go:171","msg":"trace[766836472] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"369.051517ms","start":"2026-04-13T14:20:30.6703Z","end":"2026-04-13T14:20:31.039352Z","steps":["trace[766836472] 'process raft request' (duration: 368.906333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:31.039517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:30.670277Z","time spent":"369.16433ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:20:31.03981Z","caller":"traceutil/trace.go:171","msg":"trace[1443511350] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1183; }","duration":"328.49764ms","start":"2026-04-13T14:20:30.7113Z","end":"2026-04-13T14:20:31.039798Z","steps":["trace[1443511350] 'read index received' (duration: 327.968086ms)","trace[1443511350] 'applied index is now lower than readState.Index' (duration: 528.914µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:20:31.039883Z","caller":"traceutil/trace.go:171","msg":"trace[1709703828] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"368.740068ms","start":"2026-04-13T14:20:30.671135Z","end":"2026-04-13T14:20:31.039875Z","steps":["trace[1709703828] 'process raft request' (duration: 368.610444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:31.039937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:30.671121Z","time spent":"368.779759ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:20:31.040019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.905621ms","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-04-13T14:20:31.040057Z","caller":"traceutil/trace.go:171","msg":"trace[73169834] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1138; }","duration":"102.979483ms","start":"2026-04-13T14:20:30.937066Z","end":"2026-04-13T14:20:31.040045Z","steps":["trace[73169834] 'agreement among raft nodes before linearized reading' (duration: 102.86666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:31.040083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.779678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-13T14:20:31.040108Z","caller":"traceutil/trace.go:171","msg":"trace[1473212923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1138; }","duration":"328.849439ms","start":"2026-04-13T14:20:30.711251Z","end":"2026-04-13T14:20:31.040101Z","steps":["trace[1473212923] 'agreement among raft nodes before linearized reading' (duration: 328.771027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:31.04013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:30.711232Z","time spent":"328.89205ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T14:20:47.184281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.980322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:20:47.184511Z","caller":"traceutil/trace.go:171","msg":"trace[852871062] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"660.910234ms","start":"2026-04-13T14:20:46.523575Z","end":"2026-04-13T14:20:47.184485Z","steps":["trace[852871062] 'process raft request' (duration: 183.634954ms)","trace[852871062] 'compare' (duration: 476.678294ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:20:47.184658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:46.523555Z","time spent":"661.030907ms","remote":"127.0.0.1:33002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:20:47.184543Z","caller":"traceutil/trace.go:171","msg":"trace[707036886] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"660.943145ms","start":"2026-04-13T14:20:46.523577Z","end":"2026-04-13T14:20:47.184521Z","steps":["trace[707036886] 'process raft request' (duration: 660.850142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:47.184753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:46.523557Z","time spent":"661.159961ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:20:47.576857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.477303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T14:20:47.577053Z","caller":"traceutil/trace.go:171","msg":"trace[1716440171] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"1.053414863s","start":"2026-04-13T14:20:46.523618Z","end":"2026-04-13T14:20:47.577033Z","steps":["trace[1716440171] 'process raft request' (duration: 809.684143ms)","trace[1716440171] 'compare' (duration: 243.241466ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:20:47.577132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:46.523555Z","time spent":"1.053545067s","remote":"127.0.0.1:33342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:20:47.577412Z","caller":"traceutil/trace.go:171","msg":"trace[738673416] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"483.015723ms","start":"2026-04-13T14:20:47.094348Z","end":"2026-04-13T14:20:47.577364Z","steps":["trace[738673416] 'process raft request' (duration: 482.639033ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:20:47.57751Z","caller":"traceutil/trace.go:171","msg":"trace[1851406610] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1259; }","duration":"476.55649ms","start":"2026-04-13T14:20:47.100759Z","end":"2026-04-13T14:20:47.577315Z","steps":["trace[1851406610] 'read index received' (duration: 232.560833ms)","trace[1851406610] 'applied index is now lower than readState.Index' (duration: 243.994647ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:20:47.577556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.794116ms","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-04-13T14:20:47.577608Z","caller":"traceutil/trace.go:171","msg":"trace[633368697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1210; }","duration":"476.872159ms","start":"2026-04-13T14:20:47.10072Z","end":"2026-04-13T14:20:47.577593Z","steps":["trace[633368697] 'agreement among raft nodes before linearized reading' (duration: 476.710484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:47.577609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:47.094327Z","time spent":"483.179096ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:20:47.577642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:47.100707Z","time spent":"476.92745ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T14:20:47.578717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.336293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T14:20:47.578767Z","caller":"traceutil/trace.go:171","msg":"trace[1358223270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1210; }","duration":"465.408194ms","start":"2026-04-13T14:20:47.113347Z","end":"2026-04-13T14:20:47.578755Z","steps":["trace[1358223270] 'agreement among raft nodes before linearized reading' (duration: 465.26826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:47.578881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.001036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-13T14:20:47.57889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.86856ms","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-04-13T14:20:47.578921Z","caller":"traceutil/trace.go:171","msg":"trace[982969976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1210; }","duration":"464.936282ms","start":"2026-04-13T14:20:47.113976Z","end":"2026-04-13T14:20:47.578912Z","steps":["trace[982969976] 'agreement among raft nodes before linearized reading' (duration: 464.85254ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:20:47.57892Z","caller":"traceutil/trace.go:171","msg":"trace[47442481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1210; }","duration":"464.067948ms","start":"2026-04-13T14:20:47.11484Z","end":"2026-04-13T14:20:47.578908Z","steps":["trace[47442481] 'agreement among raft nodes before linearized reading' (duration: 463.957245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:47.578945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:47.113956Z","time spent":"464.983293ms","remote":"127.0.0.1:33106","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-04-13T14:20:47.578953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:47.11483Z","time spent":"464.115749ms","remote":"127.0.0.1:33106","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-04-13T14:20:47.579336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:47.113336Z","time spent":"465.453916ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T14:20:49.696261Z","caller":"traceutil/trace.go:171","msg":"trace[84691935] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"111.639914ms","start":"2026-04-13T14:20:49.584597Z","end":"2026-04-13T14:20:49.696237Z","steps":["trace[84691935] 'process raft request' (duration: 95.332731ms)","trace[84691935] 'compare' (duration: 16.19372ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:20:50.149935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.244385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T14:20:50.150007Z","caller":"traceutil/trace.go:171","msg":"trace[1144847264] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1228; }","duration":"319.375419ms","start":"2026-04-13T14:20:49.830615Z","end":"2026-04-13T14:20:50.14999Z","steps":["trace[1144847264] 'count revisions from in-memory index tree' (duration: 319.154433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:50.150044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:49.830592Z","time spent":"319.44418ms","remote":"127.0.0.1:60444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-13T14:20:50.150392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.796823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T14:20:50.150436Z","caller":"traceutil/trace.go:171","msg":"trace[342290669] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1228; }","duration":"347.887435ms","start":"2026-04-13T14:20:49.802536Z","end":"2026-04-13T14:20:50.150423Z","steps":["trace[342290669] 'range keys from in-memory index tree' (duration: 347.627088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:20:50.150467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:20:49.802508Z","time spent":"347.951507ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T14:20:50.432658Z","caller":"traceutil/trace.go:171","msg":"trace[1678433115] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"278.434591ms","start":"2026-04-13T14:20:50.154206Z","end":"2026-04-13T14:20:50.432641Z","steps":["trace[1678433115] 'process raft request' (duration: 278.316598ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:02.185078Z","caller":"traceutil/trace.go:171","msg":"trace[1313064491] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"362.624045ms","start":"2026-04-13T14:21:01.822419Z","end":"2026-04-13T14:21:02.185043Z","steps":["trace[1313064491] 'read index received' (duration: 362.365619ms)","trace[1313064491] 'applied index is now lower than readState.Index' (duration: 257.236µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:21:02.185424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.919273ms","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-04-13T14:21:02.185443Z","caller":"traceutil/trace.go:171","msg":"trace[1462775099] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"451.837934ms","start":"2026-04-13T14:21:01.733271Z","end":"2026-04-13T14:21:02.185109Z","steps":["trace[1462775099] 'process raft request' (duration: 451.599888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:02.185543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.768712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T14:21:02.185581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.8091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-13T14:21:02.18561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.211763ms","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-04-13T14:21:02.185645Z","caller":"traceutil/trace.go:171","msg":"trace[1699544513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1305; }","duration":"316.886922ms","start":"2026-04-13T14:21:01.86874Z","end":"2026-04-13T14:21:02.185627Z","steps":["trace[1699544513] 'agreement among raft nodes before linearized reading' (duration: 316.772719ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:02.185641Z","caller":"traceutil/trace.go:171","msg":"trace[652436791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"121.907545ms","start":"2026-04-13T14:21:02.063709Z","end":"2026-04-13T14:21:02.185617Z","steps":["trace[652436791] 'agreement among raft nodes before linearized reading' (duration: 121.738131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:02.185591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.989867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-13T14:21:02.185664Z","caller":"traceutil/trace.go:171","msg":"trace[318223937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1305; }","duration":"316.268444ms","start":"2026-04-13T14:21:01.86938Z","end":"2026-04-13T14:21:02.185648Z","steps":["trace[318223937] 'agreement among raft nodes before linearized reading' (duration: 316.11032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:02.185697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:01.868725Z","time spent":"316.963204ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T14:21:02.185718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:01.86928Z","time spent":"316.42572ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T14:21:02.185714Z","caller":"traceutil/trace.go:171","msg":"trace[1278240631] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1305; }","duration":"160.115151ms","start":"2026-04-13T14:21:02.025582Z","end":"2026-04-13T14:21:02.185697Z","steps":["trace[1278240631] 'agreement among raft nodes before linearized reading' (duration: 159.817953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:02.185862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:01.733222Z","time spent":"452.372558ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:21:02.18597Z","caller":"traceutil/trace.go:171","msg":"trace[722969259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1305; }","duration":"363.558801ms","start":"2026-04-13T14:21:01.822367Z","end":"2026-04-13T14:21:02.185926Z","steps":["trace[722969259] 'agreement among raft nodes before linearized reading' (duration: 362.81684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:02.186037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:01.822346Z","time spent":"363.674264ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T14:21:02.185458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.694696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T14:21:02.186241Z","caller":"traceutil/trace.go:171","msg":"trace[765665964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1305; }","duration":"317.470369ms","start":"2026-04-13T14:21:01.868688Z","end":"2026-04-13T14:21:02.186158Z","steps":["trace[765665964] 'agreement among raft nodes before linearized reading' (duration: 316.598775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:02.186285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:01.868666Z","time spent":"317.607342ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T14:21:02.319581Z","caller":"traceutil/trace.go:171","msg":"trace[13095451] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"129.012785ms","start":"2026-04-13T14:21:02.190529Z","end":"2026-04-13T14:21:02.319542Z","steps":["trace[13095451] 'process raft request' (duration: 106.760064ms)","trace[13095451] 'compare' (duration: 21.994054ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:21:02.319649Z","caller":"traceutil/trace.go:171","msg":"trace[122591212] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"128.894372ms","start":"2026-04-13T14:21:02.19073Z","end":"2026-04-13T14:21:02.319624Z","steps":["trace[122591212] 'process raft request' (duration: 128.848511ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:02.319682Z","caller":"traceutil/trace.go:171","msg":"trace[880746060] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"129.126528ms","start":"2026-04-13T14:21:02.190527Z","end":"2026-04-13T14:21:02.319654Z","steps":["trace[880746060] 'process raft request' (duration: 128.899972ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:02.319776Z","caller":"traceutil/trace.go:171","msg":"trace[1997595385] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"129.048656ms","start":"2026-04-13T14:21:02.190716Z","end":"2026-04-13T14:21:02.319765Z","steps":["trace[1997595385] 'process raft request' (duration: 128.754908ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:21:02.861633Z","caller":"traceutil/trace.go:171","msg":"trace[745343369] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"375.307663ms","start":"2026-04-13T14:21:02.486299Z","end":"2026-04-13T14:21:02.861607Z","steps":["trace[745343369] 'read index received' (duration: 313.051789ms)","trace[745343369] 'applied index is now lower than readState.Index' (duration: 62.250004ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:21:02.86176Z","caller":"traceutil/trace.go:171","msg":"trace[1589669627] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"402.042122ms","start":"2026-04-13T14:21:02.459705Z","end":"2026-04-13T14:21:02.861747Z","steps":["trace[1589669627] 'process raft request' (duration: 339.723868ms)","trace[1589669627] 'compare' (duration: 62.055648ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:21:02.86186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:02.459679Z","time spent":"402.108144ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T14:21:02.861879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.541643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-13T14:21:02.861926Z","caller":"traceutil/trace.go:171","msg":"trace[216109130] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1311; }","duration":"284.624216ms","start":"2026-04-13T14:21:02.577288Z","end":"2026-04-13T14:21:02.861913Z","steps":["trace[216109130] 'agreement among raft nodes before linearized reading' (duration: 284.481132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:02.862053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.764294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T14:21:02.862078Z","caller":"traceutil/trace.go:171","msg":"trace[2061433404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1311; }","duration":"375.829186ms","start":"2026-04-13T14:21:02.486241Z","end":"2026-04-13T14:21:02.86207Z","steps":["trace[2061433404] 'agreement among raft nodes before linearized reading' (duration: 375.758864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:21:02.862103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:21:02.48621Z","time spent":"375.886798ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T14:24:14.016711Z","caller":"traceutil/trace.go:171","msg":"trace[1370943436] linearizableReadLoop","detail":"{readStateIndex:2309; appliedIndex:2308; }","duration":"111.733351ms","start":"2026-04-13T14:24:13.904959Z","end":"2026-04-13T14:24:14.016693Z","steps":["trace[1370943436] 'read index received' (duration: 111.528806ms)","trace[1370943436] 'applied index is now lower than readState.Index' (duration: 203.905µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:24:14.016792Z","caller":"traceutil/trace.go:171","msg":"trace[1251224501] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"343.384718ms","start":"2026-04-13T14:24:13.673381Z","end":"2026-04-13T14:24:14.016766Z","steps":["trace[1251224501] 'process raft request' (duration: 343.177062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:24:14.016917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.963686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-13T14:24:14.01692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T14:24:13.673365Z","time spent":"343.477819ms","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T14:24:14.016945Z","caller":"traceutil/trace.go:171","msg":"trace[648659849] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2216; }","duration":"112.032098ms","start":"2026-04-13T14:24:13.904906Z","end":"2026-04-13T14:24:14.016938Z","steps":["trace[648659849] 'agreement among raft nodes before linearized reading' (duration: 111.899895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:24:14.017118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.043483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-13T14:24:14.017189Z","caller":"traceutil/trace.go:171","msg":"trace[1516973026] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2216; }","duration":"109.141946ms","start":"2026-04-13T14:24:13.908028Z","end":"2026-04-13T14:24:14.01717Z","steps":["trace[1516973026] 'agreement among raft nodes before linearized reading' (duration: 108.938691ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:24:45.089356Z","caller":"traceutil/trace.go:171","msg":"trace[131599695] linearizableReadLoop","detail":"{readStateIndex:2464; appliedIndex:2463; }","duration":"105.414853ms","start":"2026-04-13T14:24:44.983922Z","end":"2026-04-13T14:24:45.089337Z","steps":["trace[131599695] 'read index received' (duration: 105.28229ms)","trace[131599695] 'applied index is now lower than readState.Index' (duration: 131.553µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T14:24:45.08957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.629128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" ","response":"range_response_count:1 size:1189"} {"level":"info","ts":"2026-04-13T14:24:45.089599Z","caller":"traceutil/trace.go:171","msg":"trace[890883476] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-nodeplugin-http-metrics; range_end:; response_count:1; response_revision:2365; }","duration":"105.687329ms","start":"2026-04-13T14:24:44.983904Z","end":"2026-04-13T14:24:45.089591Z","steps":["trace[890883476] 'agreement among raft nodes before linearized reading' (duration: 105.537766ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:24:45.089389Z","caller":"traceutil/trace.go:171","msg":"trace[2045756228] transaction","detail":"{read_only:false; response_revision:2365; number_of_response:1; }","duration":"116.233014ms","start":"2026-04-13T14:24:44.973137Z","end":"2026-04-13T14:24:45.08937Z","steps":["trace[2045756228] 'process raft request' (duration: 116.06073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:24:45.116954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.244315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics\" ","response":"range_response_count:1 size:1191"} {"level":"info","ts":"2026-04-13T14:24:45.117008Z","caller":"traceutil/trace.go:171","msg":"trace[1747550270] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics; range_end:; response_count:1; response_revision:2366; }","duration":"126.326617ms","start":"2026-04-13T14:24:44.990665Z","end":"2026-04-13T14:24:45.116992Z","steps":["trace[1747550270] 'agreement among raft nodes before linearized reading' (duration: 126.225294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T14:24:45.472281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.275864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-dns\" ","response":"range_response_count:1 size:1211"} {"level":"info","ts":"2026-04-13T14:24:45.472423Z","caller":"traceutil/trace.go:171","msg":"trace[1763838305] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-dns; range_end:; response_count:1; response_revision:2367; }","duration":"120.48926ms","start":"2026-04-13T14:24:45.351907Z","end":"2026-04-13T14:24:45.472396Z","steps":["trace[1763838305] 'range keys from in-memory index tree' (duration: 119.978347ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:24:45.680513Z","caller":"traceutil/trace.go:171","msg":"trace[729897582] transaction","detail":"{read_only:false; response_revision:2369; number_of_response:1; }","duration":"110.118281ms","start":"2026-04-13T14:24:45.570369Z","end":"2026-04-13T14:24:45.680487Z","steps":["trace[729897582] 'process raft request' (duration: 14.807901ms)","trace[729897582] 'compare' (duration: 95.208487ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T14:24:45.698889Z","caller":"traceutil/trace.go:171","msg":"trace[1355781444] transaction","detail":"{read_only:false; response_revision:2370; number_of_response:1; }","duration":"128.43815ms","start":"2026-04-13T14:24:45.570436Z","end":"2026-04-13T14:24:45.698874Z","steps":["trace[1355781444] 'process raft request' (duration: 128.263786ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T14:24:45.699077Z","caller":"traceutil/trace.go:171","msg":"trace[635497295] transaction","detail":"{read_only:false; response_revision:2371; number_of_response:1; }","duration":"128.531392ms","start":"2026-04-13T14:24:45.570515Z","end":"2026-04-13T14:24:45.699046Z","steps":["trace[635497295] 'process raft request' (duration: 128.327127ms)"],"step_count":1}