{"level":"warn","ts":"2026-04-08T12:59:29.672168Z","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-08T12:59:29.672256Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30: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.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30: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-08T12:59:29.67244Z","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-08T12:59:29.672487Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-08T12:59:29.672526Z","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-08T12:59:29.673394Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-04-08T12:59:29.673558Z","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.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30: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-08T12:59:29.680722Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.878727ms"} {"level":"info","ts":"2026-04-08T12:59:29.692639Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-04-08T12:59:29.692833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T12:59:29.692921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-04-08T12:59:29.693011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T12:59:29.693061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-04-08T12:59:29.693137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-04-08T12:59:29.704504Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T12:59:29.706137Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T12:59:29.707174Z","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-08T12:59:29.70948Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T12:59:29.710024Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T12:59:29.71085Z","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-08T12:59:29.711269Z","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-08T12:59:29.711299Z","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-08T12:59:29.711367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-04-08T12:59:29.711506Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-08T12:59:29.714266Z","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-08T12:59:29.714317Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-08T12:59:29.714346Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-08T12:59:29.714523Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T12:59:29.714567Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T12:59:30.19352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T12:59:30.193577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T12:59:30.193607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-04-08T12:59:30.193632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-04-08T12:59:30.193643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-08T12:59:30.193658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-04-08T12:59:30.193671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-08T12:59:30.195201Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:59:30.196501Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T12:59:30.196512Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T12:59:30.197012Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:59:30.197082Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T12:59:30.197219Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:59:30.197274Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:59:30.197229Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T12:59:30.198081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T12:59:30.196488Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T12:59:30.208341Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"warn","ts":"2026-04-08T12:59:33.516402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.283302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:59:33.516547Z","caller":"traceutil/trace.go:171","msg":"trace[392951268] linearizableReadLoop","detail":"{readStateIndex:136; appliedIndex:135; }","duration":"420.624586ms","start":"2026-04-08T12:59:33.095902Z","end":"2026-04-08T12:59:33.516526Z","steps":["trace[392951268] 'read index received' (duration: 36.719633ms)","trace[392951268] 'applied index is now lower than readState.Index' (duration: 383.903563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:59:33.517003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.026584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:59:33.517075Z","caller":"traceutil/trace.go:171","msg":"trace[725836069] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:133; }","duration":"421.224957ms","start":"2026-04-08T12:59:33.095835Z","end":"2026-04-08T12:59:33.51706Z","steps":["trace[725836069] 'agreement among raft nodes before linearized reading' (duration: 420.874341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:33.517148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:33.095813Z","time spent":"421.323749ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-04-08T12:59:33.516587Z","caller":"traceutil/trace.go:171","msg":"trace[486045610] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"826.684967ms","start":"2026-04-08T12:59:32.689862Z","end":"2026-04-08T12:59:33.516547Z","steps":["trace[486045610] 'process raft request' (duration: 442.841265ms)","trace[486045610] 'compare' (duration: 383.147929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:59:33.517462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:32.689853Z","time spent":"827.531612ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":975,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T12:59:34.115921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.067612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:59:34.115986Z","caller":"traceutil/trace.go:171","msg":"trace[1328189675] linearizableReadLoop","detail":"{readStateIndex:138; appliedIndex:137; }","duration":"560.214209ms","start":"2026-04-08T12:59:33.555761Z","end":"2026-04-08T12:59:34.115975Z","steps":["trace[1328189675] 'read index received' (duration: 432.997094ms)","trace[1328189675] 'applied index is now lower than readState.Index' (duration: 127.216505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:59:34.116349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.43514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T12:59:34.116427Z","caller":"traceutil/trace.go:171","msg":"trace[1464635629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:135; }","duration":"495.529251ms","start":"2026-04-08T12:59:33.620867Z","end":"2026-04-08T12:59:34.116396Z","steps":["trace[1464635629] 'agreement among raft nodes before linearized reading' (duration: 495.370499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:34.116357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.572505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T12:59:34.116555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:33.620856Z","time spent":"495.598094ms","remote":"127.0.0.1:36906","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-08T12:59:34.116535Z","caller":"traceutil/trace.go:171","msg":"trace[1838556794] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; response_count:0; response_revision:135; }","duration":"560.80506ms","start":"2026-04-08T12:59:33.555712Z","end":"2026-04-08T12:59:34.116517Z","steps":["trace[1838556794] 'agreement among raft nodes before linearized reading' (duration: 560.489694ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:34.11603Z","caller":"traceutil/trace.go:171","msg":"trace[56207523] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"592.085034ms","start":"2026-04-08T12:59:33.523938Z","end":"2026-04-08T12:59:34.116024Z","steps":["trace[56207523] 'process raft request' (duration: 464.825138ms)","trace[56207523] 'compare' (duration: 126.95389ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:59:34.116706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:33.523919Z","time spent":"592.769947ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T12:59:34.116759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:33.5557Z","time spent":"560.959172ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":29,"request content":"key:\"/registry/clusterroles/system:controller:certificate-controller\" "} {"level":"warn","ts":"2026-04-08T12:59:34.116809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.922049ms","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-08T12:59:34.11695Z","caller":"traceutil/trace.go:171","msg":"trace[1126789900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:135; }","duration":"496.153203ms","start":"2026-04-08T12:59:33.62077Z","end":"2026-04-08T12:59:34.116923Z","steps":["trace[1126789900] 'agreement among raft nodes before linearized reading' (duration: 495.485061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:34.117016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:33.620756Z","time spent":"496.246224ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T13:00:00.565792Z","caller":"traceutil/trace.go:171","msg":"trace[1520098246] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"170.753352ms","start":"2026-04-08T13:00:00.395024Z","end":"2026-04-08T13:00:00.565777Z","steps":["trace[1520098246] 'process raft request' (duration: 166.128787ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:00.566615Z","caller":"traceutil/trace.go:171","msg":"trace[2139995377] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:522; }","duration":"136.597526ms","start":"2026-04-08T13:00:00.429977Z","end":"2026-04-08T13:00:00.566574Z","steps":["trace[2139995377] 'read index received' (duration: 131.309819ms)","trace[2139995377] 'applied index is now lower than readState.Index' (duration: 5.286737ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:00:00.566746Z","caller":"traceutil/trace.go:171","msg":"trace[1187849144] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"146.08946ms","start":"2026-04-08T13:00:00.420646Z","end":"2026-04-08T13:00:00.566735Z","steps":["trace[1187849144] 'process raft request' (duration: 145.843775ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:00.566775Z","caller":"traceutil/trace.go:171","msg":"trace[767160574] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"163.284476ms","start":"2026-04-08T13:00:00.403452Z","end":"2026-04-08T13:00:00.566736Z","steps":["trace[767160574] 'process raft request' (duration: 162.879858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:00.566971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.980143ms","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-08T13:00:00.567004Z","caller":"traceutil/trace.go:171","msg":"trace[659197537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:506; }","duration":"137.050824ms","start":"2026-04-08T13:00:00.429944Z","end":"2026-04-08T13:00:00.566995Z","steps":["trace[659197537] 'agreement among raft nodes before linearized reading' (duration: 136.852951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:01.776315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.581311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-04-08T13:00:01.776568Z","caller":"traceutil/trace.go:171","msg":"trace[617268722] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:550; }","duration":"127.894737ms","start":"2026-04-08T13:00:01.64865Z","end":"2026-04-08T13:00:01.776545Z","steps":["trace[617268722] 'range keys from in-memory index tree' (duration: 127.439018ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:06.533386Z","caller":"traceutil/trace.go:171","msg":"trace[1163936320] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"106.642676ms","start":"2026-04-08T13:00:06.426725Z","end":"2026-04-08T13:00:06.533367Z","steps":["trace[1163936320] 'process raft request' (duration: 36.994518ms)","trace[1163936320] 'compare' (duration: 69.588437ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:00:07.471255Z","caller":"traceutil/trace.go:171","msg":"trace[1528076820] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"720.873556ms","start":"2026-04-08T13:00:06.750355Z","end":"2026-04-08T13:00:07.471229Z","steps":["trace[1528076820] 'process raft request' (duration: 720.711273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:07.471491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:00:06.750327Z","time spent":"721.09323ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T13:00:07.549928Z","caller":"traceutil/trace.go:171","msg":"trace[807535044] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"357.858006ms","start":"2026-04-08T13:00:07.192043Z","end":"2026-04-08T13:00:07.549902Z","steps":["trace[807535044] 'read index received' (duration: 278.963298ms)","trace[807535044] 'applied index is now lower than readState.Index' (duration: 78.893618ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:00:07.550619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.545428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fh9lj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-08T13:00:07.550863Z","caller":"traceutil/trace.go:171","msg":"trace[1839579920] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fh9lj; range_end:; response_count:1; response_revision:586; }","duration":"358.827292ms","start":"2026-04-08T13:00:07.191997Z","end":"2026-04-08T13:00:07.550824Z","steps":["trace[1839579920] 'agreement among raft nodes before linearized reading' (duration: 358.401295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:07.551144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:00:07.191982Z","time spent":"358.941985ms","remote":"127.0.0.1:36818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-fh9lj\" "} {"level":"info","ts":"2026-04-08T13:00:07.554472Z","caller":"traceutil/trace.go:171","msg":"trace[172086307] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"550.693332ms","start":"2026-04-08T13:00:07.003672Z","end":"2026-04-08T13:00:07.554365Z","steps":["trace[172086307] 'process raft request' (duration: 481.139096ms)","trace[172086307] 'compare' (duration: 64.960572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:00:07.556659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:00:07.003645Z","time spent":"551.10939ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:00:08.404221Z","caller":"traceutil/trace.go:171","msg":"trace[746969286] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"230.630986ms","start":"2026-04-08T13:00:08.173566Z","end":"2026-04-08T13:00:08.404197Z","steps":["trace[746969286] 'read index received' (duration: 229.765964ms)","trace[746969286] 'applied index is now lower than readState.Index' (duration: 864.122µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:00:08.404367Z","caller":"traceutil/trace.go:171","msg":"trace[1172681727] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"482.144568ms","start":"2026-04-08T13:00:07.922156Z","end":"2026-04-08T13:00:08.4043Z","steps":["trace[1172681727] 'process raft request' (duration: 481.485321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:08.404551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.863691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:00:08.404635Z","caller":"traceutil/trace.go:171","msg":"trace[376056058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"231.082446ms","start":"2026-04-08T13:00:08.173526Z","end":"2026-04-08T13:00:08.404609Z","steps":["trace[376056058] 'agreement among raft nodes before linearized reading' (duration: 230.79352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:08.404741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:00:07.922128Z","time spent":"482.385033ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:00:25.01946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.795112ms","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-08T13:00:25.019574Z","caller":"traceutil/trace.go:171","msg":"trace[1363651758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:756; }","duration":"254.974296ms","start":"2026-04-08T13:00:24.764584Z","end":"2026-04-08T13:00:25.019558Z","steps":["trace[1363651758] 'range keys from in-memory index tree' (duration: 254.573977ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:25.276511Z","caller":"traceutil/trace.go:171","msg":"trace[1933254229] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"103.29643ms","start":"2026-04-08T13:00:25.173195Z","end":"2026-04-08T13:00:25.276491Z","steps":["trace[1933254229] 'read index received' (duration: 103.037664ms)","trace[1933254229] 'applied index is now lower than readState.Index' (duration: 257.816µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:00:25.276664Z","caller":"traceutil/trace.go:171","msg":"trace[641587807] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"253.141523ms","start":"2026-04-08T13:00:25.02348Z","end":"2026-04-08T13:00:25.276622Z","steps":["trace[641587807] 'process raft request' (duration: 252.796264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:25.276745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.491525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:00:25.276852Z","caller":"traceutil/trace.go:171","msg":"trace[484220579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"103.69595ms","start":"2026-04-08T13:00:25.173146Z","end":"2026-04-08T13:00:25.276842Z","steps":["trace[484220579] 'agreement among raft nodes before linearized reading' (duration: 103.428324ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:59.724284Z","caller":"traceutil/trace.go:171","msg":"trace[1961245872] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"282.309646ms","start":"2026-04-08T13:00:59.44195Z","end":"2026-04-08T13:00:59.72426Z","steps":["trace[1961245872] 'process raft request' (duration: 282.166472ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:00.146075Z","caller":"traceutil/trace.go:171","msg":"trace[1178750422] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"671.110527ms","start":"2026-04-08T13:00:59.474947Z","end":"2026-04-08T13:01:00.146058Z","steps":["trace[1178750422] 'process raft request' (duration: 671.006215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:00.146184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:00:59.474912Z","time spent":"671.21791ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:01:01.426228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224611893s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-08T13:01:01.426387Z","caller":"traceutil/trace.go:171","msg":"trace[962566835] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"1.392609677s","start":"2026-04-08T13:01:00.033759Z","end":"2026-04-08T13:01:01.426369Z","steps":["trace[962566835] 'read index received' (duration: 112.459227ms)","trace[962566835] 'applied index is now lower than readState.Index' (duration: 1.28014923s)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:01:01.426685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25295632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:01:01.426739Z","caller":"traceutil/trace.go:171","msg":"trace[920308182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"1.253035251s","start":"2026-04-08T13:01:00.173691Z","end":"2026-04-08T13:01:01.426726Z","steps":["trace[920308182] 'agreement among raft nodes before linearized reading' (duration: 1.252939309s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:01.426734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392956185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-08T13:01:01.426774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.634188ms","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-08T13:01:01.426803Z","caller":"traceutil/trace.go:171","msg":"trace[1111398216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:969; }","duration":"1.393052218s","start":"2026-04-08T13:01:00.033734Z","end":"2026-04-08T13:01:01.426786Z","steps":["trace[1111398216] 'agreement among raft nodes before linearized reading' (duration: 1.392872433s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:01.426775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.581047ms","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-08T13:01:01.426836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:00.033721Z","time spent":"1.393109419s","remote":"127.0.0.1:36906","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-08T13:01:01.426843Z","caller":"traceutil/trace.go:171","msg":"trace[1539190896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:969; }","duration":"758.70204ms","start":"2026-04-08T13:01:00.668129Z","end":"2026-04-08T13:01:01.426831Z","steps":["trace[1539190896] 'agreement among raft nodes before linearized reading' (duration: 758.527266ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:01.426842Z","caller":"traceutil/trace.go:171","msg":"trace[983838696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:969; }","duration":"758.726641ms","start":"2026-04-08T13:01:00.668095Z","end":"2026-04-08T13:01:01.426822Z","steps":["trace[983838696] 'agreement among raft nodes before linearized reading' (duration: 758.587417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:01.426766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:00.173679Z","time spent":"1.253082543s","remote":"127.0.0.1:36640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T13:01:01.426892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:00.668075Z","time spent":"758.811602ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T13:01:01.42688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:00.668121Z","time spent":"758.749031ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T13:01:01.426863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.79424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T13:01:01.426977Z","caller":"traceutil/trace.go:171","msg":"trace[1062744688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"537.941614ms","start":"2026-04-08T13:01:00.889028Z","end":"2026-04-08T13:01:01.426969Z","steps":["trace[1062744688] 'agreement among raft nodes before linearized reading' (duration: 537.75728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:01.427001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:00.888959Z","time spent":"538.036607ms","remote":"127.0.0.1:36906","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-08T13:01:04.511165Z","caller":"traceutil/trace.go:171","msg":"trace[2044288222] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"354.534062ms","start":"2026-04-08T13:01:04.156615Z","end":"2026-04-08T13:01:04.511149Z","steps":["trace[2044288222] 'process raft request' (duration: 354.43818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:04.511286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:04.156597Z","time spent":"354.618414ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:01:04.560352Z","caller":"traceutil/trace.go:171","msg":"trace[894612911] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1014; }","duration":"387.609142ms","start":"2026-04-08T13:01:04.172725Z","end":"2026-04-08T13:01:04.560334Z","steps":["trace[894612911] 'read index received' (duration: 338.334125ms)","trace[894612911] 'applied index is now lower than readState.Index' (duration: 49.274377ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:01:04.560543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.759686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:01:04.560572Z","caller":"traceutil/trace.go:171","msg":"trace[889518567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"387.865509ms","start":"2026-04-08T13:01:04.172698Z","end":"2026-04-08T13:01:04.560563Z","steps":["trace[889518567] 'agreement among raft nodes before linearized reading' (duration: 387.763076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:04.560597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:04.172689Z","time spent":"387.901939ms","remote":"127.0.0.1:36640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T13:01:04.560569Z","caller":"traceutil/trace.go:171","msg":"trace[456037028] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"403.595114ms","start":"2026-04-08T13:01:04.156891Z","end":"2026-04-08T13:01:04.560486Z","steps":["trace[456037028] 'process raft request' (duration: 403.336897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:04.560772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:04.156862Z","time spent":"403.80268ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:01:04.560821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.584101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:01:04.560915Z","caller":"traceutil/trace.go:171","msg":"trace[1358921694] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:982; }","duration":"343.748135ms","start":"2026-04-08T13:01:04.217146Z","end":"2026-04-08T13:01:04.560895Z","steps":["trace[1358921694] 'agreement among raft nodes before linearized reading' (duration: 343.53878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:04.560973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:04.217128Z","time spent":"343.833886ms","remote":"127.0.0.1:36964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-08T13:01:21.789152Z","caller":"traceutil/trace.go:171","msg":"trace[1000828899] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"246.49299ms","start":"2026-04-08T13:01:21.542631Z","end":"2026-04-08T13:01:21.789124Z","steps":["trace[1000828899] 'process raft request' (duration: 246.333157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:21.988805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.55118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:01:21.98892Z","caller":"traceutil/trace.go:171","msg":"trace[1117728040] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1073; }","duration":"442.454663ms","start":"2026-04-08T13:01:21.546445Z","end":"2026-04-08T13:01:21.988899Z","steps":["trace[1117728040] 'read index received' (duration: 242.522786ms)","trace[1117728040] 'applied index is now lower than readState.Index' (duration: 199.930257ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:01:21.989014Z","caller":"traceutil/trace.go:171","msg":"trace[1491061662] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"445.489225ms","start":"2026-04-08T13:01:21.543514Z","end":"2026-04-08T13:01:21.989003Z","steps":["trace[1491061662] 'process raft request' (duration: 314.649328ms)","trace[1491061662] 'compare' (duration: 130.420077ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:01:21.989078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:21.543495Z","time spent":"445.552836ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:01:21.989249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.387214ms","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-08T13:01:21.989315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.873602ms","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-08T13:01:21.989353Z","caller":"traceutil/trace.go:171","msg":"trace[222813674] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1037; }","duration":"442.932184ms","start":"2026-04-08T13:01:21.546411Z","end":"2026-04-08T13:01:21.989343Z","steps":["trace[222813674] 'agreement among raft nodes before linearized reading' (duration: 442.806201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:21.989381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:21.546398Z","time spent":"442.976445ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T13:01:21.989366Z","caller":"traceutil/trace.go:171","msg":"trace[2066942172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1037; }","duration":"438.492358ms","start":"2026-04-08T13:01:21.55082Z","end":"2026-04-08T13:01:21.989313Z","steps":["trace[2066942172] 'agreement among raft nodes before linearized reading' (duration: 438.270742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:21.989425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:21.550811Z","time spent":"438.60519ms","remote":"127.0.0.1:36906","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":"warn","ts":"2026-04-08T13:01:21.989752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.737845ms","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-08T13:01:21.989825Z","caller":"traceutil/trace.go:171","msg":"trace[908651877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1037; }","duration":"151.855538ms","start":"2026-04-08T13:01:21.837951Z","end":"2026-04-08T13:01:21.989807Z","steps":["trace[908651877] 'agreement among raft nodes before linearized reading' (duration: 151.601132ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:22.748894Z","caller":"traceutil/trace.go:171","msg":"trace[591445112] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"114.914556ms","start":"2026-04-08T13:01:22.633947Z","end":"2026-04-08T13:01:22.748861Z","steps":["trace[591445112] 'process raft request' (duration: 114.728322ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:23.907435Z","caller":"traceutil/trace.go:171","msg":"trace[599075783] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"110.888221ms","start":"2026-04-08T13:01:23.796517Z","end":"2026-04-08T13:01:23.907405Z","steps":["trace[599075783] 'process raft request' (duration: 110.740997ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:24.521514Z","caller":"traceutil/trace.go:171","msg":"trace[1890583557] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1080; }","duration":"424.962915ms","start":"2026-04-08T13:01:24.096529Z","end":"2026-04-08T13:01:24.521492Z","steps":["trace[1890583557] 'read index received' (duration: 69.781578ms)","trace[1890583557] 'applied index is now lower than readState.Index' (duration: 355.180227ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:01:24.521601Z","caller":"traceutil/trace.go:171","msg":"trace[1926887801] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"430.037986ms","start":"2026-04-08T13:01:24.091525Z","end":"2026-04-08T13:01:24.521563Z","steps":["trace[1926887801] 'process raft request' (duration: 429.708458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:24.521755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.211061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-08T13:01:24.521801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:24.091516Z","time spent":"430.180089ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:01:24.521797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.986151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T13:01:24.52202Z","caller":"traceutil/trace.go:171","msg":"trace[1677501772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1044; }","duration":"424.248747ms","start":"2026-04-08T13:01:24.097754Z","end":"2026-04-08T13:01:24.522002Z","steps":["trace[1677501772] 'agreement among raft nodes before linearized reading' (duration: 423.904149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:24.522069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:24.097737Z","time spent":"424.318199ms","remote":"127.0.0.1:36906","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":"warn","ts":"2026-04-08T13:01:24.522197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.680728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:01:24.522253Z","caller":"traceutil/trace.go:171","msg":"trace[83555272] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1044; }","duration":"139.76983ms","start":"2026-04-08T13:01:24.382468Z","end":"2026-04-08T13:01:24.522238Z","steps":["trace[83555272] 'agreement among raft nodes before linearized reading' (duration: 139.693658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:24.522251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.962755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:01:24.521813Z","caller":"traceutil/trace.go:171","msg":"trace[481648510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1044; }","duration":"425.309013ms","start":"2026-04-08T13:01:24.096493Z","end":"2026-04-08T13:01:24.521802Z","steps":["trace[481648510] 'agreement among raft nodes before linearized reading' (duration: 425.120709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:24.522649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:24.096406Z","time spent":"426.231025ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T13:01:24.522296Z","caller":"traceutil/trace.go:171","msg":"trace[491438484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"348.053507ms","start":"2026-04-08T13:01:24.17423Z","end":"2026-04-08T13:01:24.522284Z","steps":["trace[491438484] 'agreement among raft nodes before linearized reading' (duration: 347.7693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:24.522914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:24.174217Z","time spent":"348.687512ms","remote":"127.0.0.1:36640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T13:01:36.696231Z","caller":"traceutil/trace.go:171","msg":"trace[946208954] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"489.946209ms","start":"2026-04-08T13:01:36.206263Z","end":"2026-04-08T13:01:36.696209Z","steps":["trace[946208954] 'read index received' (duration: 489.873807ms)","trace[946208954] 'applied index is now lower than readState.Index' (duration: 71.771µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:01:36.6964Z","caller":"traceutil/trace.go:171","msg":"trace[564029491] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"742.3169ms","start":"2026-04-08T13:01:35.954026Z","end":"2026-04-08T13:01:36.696343Z","steps":["trace[564029491] 'process raft request' (duration: 742.063984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:36.696486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.203084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T13:01:36.696524Z","caller":"traceutil/trace.go:171","msg":"trace[825829293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1135; }","duration":"490.293917ms","start":"2026-04-08T13:01:36.206218Z","end":"2026-04-08T13:01:36.696512Z","steps":["trace[825829293] 'agreement among raft nodes before linearized reading' (duration: 490.110222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:36.696556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:36.206199Z","time spent":"490.349608ms","remote":"127.0.0.1:36906","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-08T13:01:36.696597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:35.954015Z","time spent":"742.498794ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:01:36.696874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.494378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:01:36.696917Z","caller":"traceutil/trace.go:171","msg":"trace[1495890450] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1135; }","duration":"461.582731ms","start":"2026-04-08T13:01:36.235323Z","end":"2026-04-08T13:01:36.696906Z","steps":["trace[1495890450] 'agreement among raft nodes before linearized reading' (duration: 461.504609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:36.696949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:36.235299Z","time spent":"461.641752ms","remote":"127.0.0.1:36736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-04-08T13:01:36.697171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.147699ms","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-08T13:01:36.697209Z","caller":"traceutil/trace.go:171","msg":"trace[1211583575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1135; }","duration":"104.22099ms","start":"2026-04-08T13:01:36.592978Z","end":"2026-04-08T13:01:36.697199Z","steps":["trace[1211583575] 'agreement among raft nodes before linearized reading' (duration: 104.128958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:36.697243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.107356ms","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-08T13:01:36.697292Z","caller":"traceutil/trace.go:171","msg":"trace[1488268527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1135; }","duration":"129.195077ms","start":"2026-04-08T13:01:36.568087Z","end":"2026-04-08T13:01:36.697282Z","steps":["trace[1488268527] 'agreement among raft nodes before linearized reading' (duration: 129.053023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:36.697511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.461096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T13:01:36.697548Z","caller":"traceutil/trace.go:171","msg":"trace[1627798543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1135; }","duration":"104.508877ms","start":"2026-04-08T13:01:36.59303Z","end":"2026-04-08T13:01:36.697538Z","steps":["trace[1627798543] 'agreement among raft nodes before linearized reading' (duration: 104.422265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:55.418028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.327546ms","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-08T13:01:55.41807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.424442ms","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-08T13:01:55.418094Z","caller":"traceutil/trace.go:171","msg":"trace[598905583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1221; }","duration":"531.390228ms","start":"2026-04-08T13:01:54.886688Z","end":"2026-04-08T13:01:55.418078Z","steps":["trace[598905583] 'range keys from in-memory index tree' (duration: 531.200243ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:55.418128Z","caller":"traceutil/trace.go:171","msg":"trace[1179854237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1221; }","duration":"527.513535ms","start":"2026-04-08T13:01:54.890598Z","end":"2026-04-08T13:01:55.418111Z","steps":["trace[1179854237] 'range keys from in-memory index tree' (duration: 527.29544ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:55.418117Z","caller":"traceutil/trace.go:171","msg":"trace[477697128] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1268; }","duration":"493.82855ms","start":"2026-04-08T13:01:54.924258Z","end":"2026-04-08T13:01:55.418087Z","steps":["trace[477697128] 'read index received' (duration: 409.431684ms)","trace[477697128] 'applied index is now lower than readState.Index' (duration: 84.395786ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:01:55.418134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:54.8866Z","time spent":"531.521541ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T13:01:55.418158Z","caller":"traceutil/trace.go:171","msg":"trace[376587789] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"519.449572ms","start":"2026-04-08T13:01:54.898695Z","end":"2026-04-08T13:01:55.418144Z","steps":["trace[376587789] 'process raft request' (duration: 434.772829ms)","trace[376587789] 'compare' (duration: 84.313305ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:01:55.418176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:54.890578Z","time spent":"527.585147ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T13:01:55.418197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.937054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-08T13:01:55.418238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:54.898675Z","time spent":"519.509583ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:01:55.418277Z","caller":"traceutil/trace.go:171","msg":"trace[571630276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1221; }","duration":"620.074326ms","start":"2026-04-08T13:01:54.798183Z","end":"2026-04-08T13:01:55.418258Z","steps":["trace[571630276] 'range keys from in-memory index tree' (duration: 619.692477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:55.418292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.040875ms","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-08T13:01:55.418319Z","caller":"traceutil/trace.go:171","msg":"trace[1602347770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1222; }","duration":"494.069245ms","start":"2026-04-08T13:01:54.924241Z","end":"2026-04-08T13:01:55.41831Z","steps":["trace[1602347770] 'agreement among raft nodes before linearized reading' (duration: 493.942952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:55.417946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.342691ms","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-08T13:01:55.418323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:54.798166Z","time spent":"620.147468ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T13:01:55.418341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:54.924185Z","time spent":"494.150738ms","remote":"127.0.0.1:36906","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-08T13:01:55.418367Z","caller":"traceutil/trace.go:171","msg":"trace[1459729839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1221; }","duration":"532.817182ms","start":"2026-04-08T13:01:54.885531Z","end":"2026-04-08T13:01:55.418348Z","steps":["trace[1459729839] 'range keys from in-memory index tree' (duration: 532.157976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:55.418413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:54.885499Z","time spent":"532.900653ms","remote":"127.0.0.1:36906","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-08T13:01:55.628113Z","caller":"traceutil/trace.go:171","msg":"trace[109633120] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"209.910726ms","start":"2026-04-08T13:01:55.418165Z","end":"2026-04-08T13:01:55.628076Z","steps":["trace[109633120] 'read index received' (duration: 172.270647ms)","trace[109633120] 'applied index is now lower than readState.Index' (duration: 37.630619ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:01:55.628094Z","caller":"traceutil/trace.go:171","msg":"trace[306300907] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"422.199939ms","start":"2026-04-08T13:01:55.205849Z","end":"2026-04-08T13:01:55.628049Z","steps":["trace[306300907] 'process raft request' (duration: 384.618692ms)","trace[306300907] 'compare' (duration: 37.353322ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:01:55.628185Z","caller":"traceutil/trace.go:171","msg":"trace[1765474122] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"201.758221ms","start":"2026-04-08T13:01:55.426402Z","end":"2026-04-08T13:01:55.62816Z","steps":["trace[1765474122] 'process raft request' (duration: 201.72069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:55.628268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.420593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:01:55.628274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:55.205833Z","time spent":"422.361873ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:01:55.628308Z","caller":"traceutil/trace.go:171","msg":"trace[1009620734] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1228; }","duration":"676.507975ms","start":"2026-04-08T13:01:54.951786Z","end":"2026-04-08T13:01:55.628294Z","steps":["trace[1009620734] 'agreement among raft nodes before linearized reading' (duration: 676.384422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:55.628339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:54.951764Z","time spent":"676.565196ms","remote":"127.0.0.1:51980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-04-08T13:01:55.628663Z","caller":"traceutil/trace.go:171","msg":"trace[381738559] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"205.038669ms","start":"2026-04-08T13:01:55.423602Z","end":"2026-04-08T13:01:55.628641Z","steps":["trace[381738559] 'process raft request' (duration: 204.423145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:55.628684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.034147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:01:55.628742Z","caller":"traceutil/trace.go:171","msg":"trace[1994605607] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1228; }","duration":"225.138399ms","start":"2026-04-08T13:01:55.403586Z","end":"2026-04-08T13:01:55.628724Z","steps":["trace[1994605607] 'agreement among raft nodes before linearized reading' (duration: 225.040137ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:55.628799Z","caller":"traceutil/trace.go:171","msg":"trace[194175462] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"205.136212ms","start":"2026-04-08T13:01:55.42365Z","end":"2026-04-08T13:01:55.628786Z","steps":["trace[194175462] 'process raft request' (duration: 204.408505ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:55.628834Z","caller":"traceutil/trace.go:171","msg":"trace[1328815234] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"205.413758ms","start":"2026-04-08T13:01:55.423382Z","end":"2026-04-08T13:01:55.628795Z","steps":["trace[1328815234] 'process raft request' (duration: 204.599249ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:55.628892Z","caller":"traceutil/trace.go:171","msg":"trace[978166596] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"204.505887ms","start":"2026-04-08T13:01:55.424374Z","end":"2026-04-08T13:01:55.62888Z","steps":["trace[978166596] 'process raft request' (duration: 203.713548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:56.435687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.71854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:01:56.435802Z","caller":"traceutil/trace.go:171","msg":"trace[1248829867] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1276; }","duration":"262.061751ms","start":"2026-04-08T13:01:56.17373Z","end":"2026-04-08T13:01:56.435792Z","steps":["trace[1248829867] 'read index received' (duration: 47.207778ms)","trace[1248829867] 'applied index is now lower than readState.Index' (duration: 214.853303ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:01:56.435872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.140293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:01:56.43589Z","caller":"traceutil/trace.go:171","msg":"trace[2094733868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1231; }","duration":"262.193985ms","start":"2026-04-08T13:01:56.17369Z","end":"2026-04-08T13:01:56.435884Z","steps":["trace[2094733868] 'agreement among raft nodes before linearized reading' (duration: 262.151304ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:56.435876Z","caller":"traceutil/trace.go:171","msg":"trace[799534103] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"422.730752ms","start":"2026-04-08T13:01:56.013115Z","end":"2026-04-08T13:01:56.435846Z","steps":["trace[799534103] 'process raft request' (duration: 422.63721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:01:56.436004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.29326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:01:56.436025Z","caller":"traceutil/trace.go:171","msg":"trace[1161161407] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1231; }","duration":"219.352692ms","start":"2026-04-08T13:01:56.216667Z","end":"2026-04-08T13:01:56.43602Z","steps":["trace[1161161407] 'agreement among raft nodes before linearized reading' (duration: 219.321061ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:56.436034Z","caller":"traceutil/trace.go:171","msg":"trace[1484743413] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"439.494481ms","start":"2026-04-08T13:01:55.996525Z","end":"2026-04-08T13:01:56.43602Z","steps":["trace[1484743413] 'process raft request' (duration: 224.400702ms)","trace[1484743413] 'compare' (duration: 214.624008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:01:56.436086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:55.996516Z","time spent":"439.551263ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T13:01:56.435991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:01:56.013087Z","time spent":"422.844805ms","remote":"127.0.0.1:36712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T13:01:56.618964Z","caller":"traceutil/trace.go:171","msg":"trace[1548029021] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"177.970943ms","start":"2026-04-08T13:01:56.440974Z","end":"2026-04-08T13:01:56.618945Z","steps":["trace[1548029021] 'read index received' (duration: 121.547125ms)","trace[1548029021] 'applied index is now lower than readState.Index' (duration: 56.423008ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:01:56.618992Z","caller":"traceutil/trace.go:171","msg":"trace[2144731263] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"178.66953ms","start":"2026-04-08T13:01:56.4403Z","end":"2026-04-08T13:01:56.61897Z","steps":["trace[2144731263] 'process raft request' (duration: 122.196971ms)","trace[2144731263] 'compare' (duration: 56.349016ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:01:56.619331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.341302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-08T13:01:56.619464Z","caller":"traceutil/trace.go:171","msg":"trace[1125987721] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1232; }","duration":"178.408363ms","start":"2026-04-08T13:01:56.440943Z","end":"2026-04-08T13:01:56.619352Z","steps":["trace[1125987721] 'agreement among raft nodes before linearized reading' (duration: 178.077815ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:56.630068Z","caller":"traceutil/trace.go:171","msg":"trace[1889882747] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"188.154896ms","start":"2026-04-08T13:01:56.441897Z","end":"2026-04-08T13:01:56.630052Z","steps":["trace[1889882747] 'process raft request' (duration: 188.077584ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:06.156089Z","caller":"traceutil/trace.go:171","msg":"trace[699786453] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"682.492208ms","start":"2026-04-08T13:02:05.473571Z","end":"2026-04-08T13:02:06.156063Z","steps":["trace[699786453] 'process raft request' (duration: 682.342314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:06.156403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.473548Z","time spent":"682.708023ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:02:06.688464Z","caller":"traceutil/trace.go:171","msg":"trace[706504952] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1342; }","duration":"1.015866715s","start":"2026-04-08T13:02:05.672537Z","end":"2026-04-08T13:02:06.688404Z","steps":["trace[706504952] 'read index received' (duration: 483.36359ms)","trace[706504952] 'applied index is now lower than readState.Index' (duration: 532.501755ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:06.688589Z","caller":"traceutil/trace.go:171","msg":"trace[2064802511] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"949.383976ms","start":"2026-04-08T13:02:05.739173Z","end":"2026-04-08T13:02:06.688557Z","steps":["trace[2064802511] 'process raft request' (duration: 949.161171ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:06.68862Z","caller":"traceutil/trace.go:171","msg":"trace[1457595129] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"1.213792315s","start":"2026-04-08T13:02:05.474691Z","end":"2026-04-08T13:02:06.688483Z","steps":["trace[1457595129] 'process raft request' (duration: 1.213373485s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:06.688769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012123835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-08T13:02:06.688772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.739156Z","time spent":"949.51835ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:02:06.688749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016169962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T13:02:06.688819Z","caller":"traceutil/trace.go:171","msg":"trace[885718055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1296; }","duration":"1.012195077s","start":"2026-04-08T13:02:05.676609Z","end":"2026-04-08T13:02:06.688804Z","steps":["trace[885718055] 'agreement among raft nodes before linearized reading' (duration: 1.011994852s)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:06.688854Z","caller":"traceutil/trace.go:171","msg":"trace[509410630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"1.016324216s","start":"2026-04-08T13:02:05.672505Z","end":"2026-04-08T13:02:06.688829Z","steps":["trace[509410630] 'agreement among raft nodes before linearized reading' (duration: 1.016043909s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:06.688915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.67249Z","time spent":"1.016415607s","remote":"127.0.0.1:36906","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":"warn","ts":"2026-04-08T13:02:06.688937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.474679Z","time spent":"1.214067371s","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:02:06.689076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.351349ms","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:7"} {"level":"info","ts":"2026-04-08T13:02:06.689563Z","caller":"traceutil/trace.go:171","msg":"trace[1595755921] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1296; }","duration":"244.833732ms","start":"2026-04-08T13:02:06.444712Z","end":"2026-04-08T13:02:06.689545Z","steps":["trace[1595755921] 'agreement among raft nodes before linearized reading' (duration: 244.335799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:06.689156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009846901s","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-08T13:02:06.689761Z","caller":"traceutil/trace.go:171","msg":"trace[2060800306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1296; }","duration":"1.010466516s","start":"2026-04-08T13:02:05.679284Z","end":"2026-04-08T13:02:06.68975Z","steps":["trace[2060800306] 'agreement among raft nodes before linearized reading' (duration: 1.00981498s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:06.689797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.679276Z","time spent":"1.010508187s","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T13:02:06.689173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005732173s","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-08T13:02:06.689943Z","caller":"traceutil/trace.go:171","msg":"trace[419904891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1296; }","duration":"1.006520143s","start":"2026-04-08T13:02:05.683413Z","end":"2026-04-08T13:02:06.689933Z","steps":["trace[419904891] 'agreement among raft nodes before linearized reading' (duration: 1.005644571s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:06.689985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.683405Z","time spent":"1.006568104s","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T13:02:06.688865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.676586Z","time spent":"1.012269258s","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T13:02:06.689242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005543688s","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-08T13:02:06.69024Z","caller":"traceutil/trace.go:171","msg":"trace[903016673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1296; }","duration":"1.006542632s","start":"2026-04-08T13:02:05.683681Z","end":"2026-04-08T13:02:06.690224Z","steps":["trace[903016673] 'agreement among raft nodes before linearized reading' (duration: 1.005510057s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:06.690288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.683676Z","time spent":"1.006601633s","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T13:02:07.099051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.479302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:02:07.099683Z","caller":"traceutil/trace.go:171","msg":"trace[1390654651] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"405.590472ms","start":"2026-04-08T13:02:06.694059Z","end":"2026-04-08T13:02:07.09965Z","steps":["trace[1390654651] 'process raft request' (duration: 119.411813ms)","trace[1390654651] 'compare' (duration: 285.206506ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:07.099753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.694035Z","time spent":"405.690735ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:02:07.101452Z","caller":"traceutil/trace.go:171","msg":"trace[251249617] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"403.692277ms","start":"2026-04-08T13:02:06.697731Z","end":"2026-04-08T13:02:07.101424Z","steps":["trace[251249617] 'process raft request' (duration: 403.648216ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:07.1018Z","caller":"traceutil/trace.go:171","msg":"trace[2087420572] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"405.91956ms","start":"2026-04-08T13:02:06.695861Z","end":"2026-04-08T13:02:07.101781Z","steps":["trace[2087420572] 'process raft request' (duration: 404.968027ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:07.101821Z","caller":"traceutil/trace.go:171","msg":"trace[782377268] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"406.037993ms","start":"2026-04-08T13:02:06.695724Z","end":"2026-04-08T13:02:07.101762Z","steps":["trace[782377268] 'process raft request' (duration: 404.922055ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:07.101623Z","caller":"traceutil/trace.go:171","msg":"trace[607054616] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"405.709525ms","start":"2026-04-08T13:02:06.695885Z","end":"2026-04-08T13:02:07.101594Z","steps":["trace[607054616] 'process raft request' (duration: 404.899055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.102113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.695865Z","time spent":"406.166346ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:02:07.101841Z","caller":"traceutil/trace.go:171","msg":"trace[713229421] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"404.213879ms","start":"2026-04-08T13:02:06.697605Z","end":"2026-04-08T13:02:07.101818Z","steps":["trace[713229421] 'process raft request' (duration: 403.703027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.102233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.697594Z","time spent":"404.586639ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:02:07.10215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.695854Z","time spent":"406.113205ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:02:07.102579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.695698Z","time spent":"406.32359ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:02:07.103238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.697721Z","time spent":"403.979393ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:02:07.987459Z","caller":"traceutil/trace.go:171","msg":"trace[1136312145] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"103.470002ms","start":"2026-04-08T13:02:07.883963Z","end":"2026-04-08T13:02:07.987433Z","steps":["trace[1136312145] 'process raft request' (duration: 103.295938ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:08.288044Z","caller":"traceutil/trace.go:171","msg":"trace[255679487] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"126.878172ms","start":"2026-04-08T13:02:08.161137Z","end":"2026-04-08T13:02:08.288015Z","steps":["trace[255679487] 'read index received' (duration: 126.682937ms)","trace[255679487] 'applied index is now lower than readState.Index' (duration: 194.375µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:08.288137Z","caller":"traceutil/trace.go:171","msg":"trace[1885401475] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"149.898892ms","start":"2026-04-08T13:02:08.13819Z","end":"2026-04-08T13:02:08.288089Z","steps":["trace[1885401475] 'process raft request' (duration: 149.644146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:08.288279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.113617ms","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-08T13:02:08.288363Z","caller":"traceutil/trace.go:171","msg":"trace[2023686483] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1304; }","duration":"127.209299ms","start":"2026-04-08T13:02:08.161105Z","end":"2026-04-08T13:02:08.288314Z","steps":["trace[2023686483] 'agreement among raft nodes before linearized reading' (duration: 127.025995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:13.072573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.199151ms","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-08T13:02:13.072668Z","caller":"traceutil/trace.go:171","msg":"trace[1204081268] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1322; }","duration":"759.343435ms","start":"2026-04-08T13:02:12.313302Z","end":"2026-04-08T13:02:13.072646Z","steps":["trace[1204081268] 'range keys from in-memory index tree' (duration: 759.020347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:13.072618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.400069ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:02:13.07271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:12.313286Z","time spent":"759.414316ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-08T13:02:13.072748Z","caller":"traceutil/trace.go:171","msg":"trace[1193646759] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1322; }","duration":"860.538443ms","start":"2026-04-08T13:02:12.212187Z","end":"2026-04-08T13:02:13.072725Z","steps":["trace[1193646759] 'range keys from in-memory index tree' (duration: 860.384779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:13.072659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.16538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:02:13.072934Z","caller":"traceutil/trace.go:171","msg":"trace[1253877962] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1322; }","duration":"515.489158ms","start":"2026-04-08T13:02:12.557412Z","end":"2026-04-08T13:02:13.072901Z","steps":["trace[1253877962] 'count revisions from in-memory index tree' (duration: 515.057668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:13.073001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:12.557388Z","time spent":"515.590251ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-04-08T13:02:13.083142Z","caller":"traceutil/trace.go:171","msg":"trace[404241499] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"354.191134ms","start":"2026-04-08T13:02:12.728927Z","end":"2026-04-08T13:02:13.083118Z","steps":["trace[404241499] 'process raft request' (duration: 353.836516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:13.083261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:12.728902Z","time spent":"354.291696ms","remote":"127.0.0.1:36906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:02:47.466978Z","caller":"traceutil/trace.go:171","msg":"trace[485204958] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"102.56548ms","start":"2026-04-08T13:02:47.364382Z","end":"2026-04-08T13:02:47.466948Z","steps":["trace[485204958] 'process raft request' (duration: 100.241904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:47.467033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.707143ms","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-08T13:02:47.467229Z","caller":"traceutil/trace.go:171","msg":"trace[1062683032] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1485; }","duration":"102.817795ms","start":"2026-04-08T13:02:47.364265Z","end":"2026-04-08T13:02:47.467083Z","steps":["trace[1062683032] 'agreement among raft nodes before linearized reading' (duration: 102.62494ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:55.871207Z","caller":"traceutil/trace.go:171","msg":"trace[1601861069] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"134.118252ms","start":"2026-04-08T13:03:55.737068Z","end":"2026-04-08T13:03:55.871186Z","steps":["trace[1601861069] 'process raft request' (duration: 35.871764ms)","trace[1601861069] 'compare' (duration: 97.91845ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:04:48.228195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.560725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T13:04:48.228312Z","caller":"traceutil/trace.go:171","msg":"trace[573817740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2035; }","duration":"120.713628ms","start":"2026-04-08T13:04:48.10757Z","end":"2026-04-08T13:04:48.228283Z","steps":["trace[573817740] 'range keys from in-memory index tree' (duration: 120.233288ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:48.436141Z","caller":"traceutil/trace.go:171","msg":"trace[462454062] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"203.273499ms","start":"2026-04-08T13:04:48.232827Z","end":"2026-04-08T13:04:48.4361Z","steps":["trace[462454062] 'process raft request' (duration: 203.006702ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:48.436578Z","caller":"traceutil/trace.go:171","msg":"trace[390883788] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"203.623207ms","start":"2026-04-08T13:04:48.232911Z","end":"2026-04-08T13:04:48.436534Z","steps":["trace[390883788] 'process raft request' (duration: 203.138445ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:48.436916Z","caller":"traceutil/trace.go:171","msg":"trace[1126394470] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"203.41417ms","start":"2026-04-08T13:04:48.233475Z","end":"2026-04-08T13:04:48.436889Z","steps":["trace[1126394470] 'process raft request' (duration: 202.867797ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:05:21.908067Z","caller":"traceutil/trace.go:171","msg":"trace[1958698212] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"107.328998ms","start":"2026-04-08T13:05:21.800702Z","end":"2026-04-08T13:05:21.90803Z","steps":["trace[1958698212] 'process raft request' (duration: 107.117073ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:05:21.908374Z","caller":"traceutil/trace.go:171","msg":"trace[662046127] linearizableReadLoop","detail":"{readStateIndex:2308; appliedIndex:2308; }","duration":"102.667597ms","start":"2026-04-08T13:05:21.805685Z","end":"2026-04-08T13:05:21.908353Z","steps":["trace[662046127] 'read index received' (duration: 102.656027ms)","trace[662046127] 'applied index is now lower than readState.Index' (duration: 6.59µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:05:21.908565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.849191ms","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-08T13:05:21.908614Z","caller":"traceutil/trace.go:171","msg":"trace[103038664] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2219; }","duration":"102.955304ms","start":"2026-04-08T13:05:21.805644Z","end":"2026-04-08T13:05:21.9086Z","steps":["trace[103038664] 'agreement among raft nodes before linearized reading' (duration: 102.820021ms)"],"step_count":1}