{"level":"warn","ts":"2026-04-20T10:10:13.415614Z","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-20T10:10:13.415727Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.69: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.69:2380","--initial-cluster=instance=https://199.204.45.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.69: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-20T10:10:13.415807Z","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-20T10:10:13.415822Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-20T10:10:13.415841Z","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-20T10:10:13.416457Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"]} {"level":"info","ts":"2026-04-20T10:10:13.416542Z","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.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.69: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-20T10:10:13.532153Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"115.399456ms"} {"level":"info","ts":"2026-04-20T10:10:13.550777Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fe334179879fd0aa","cluster-id":"d79a8cccc23ced73"} {"level":"info","ts":"2026-04-20T10:10:13.550929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T10:10:13.550971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 0"} {"level":"info","ts":"2026-04-20T10:10:13.550999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fe334179879fd0aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T10:10:13.551023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 1"} {"level":"info","ts":"2026-04-20T10:10:13.551094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"warn","ts":"2026-04-20T10:10:13.576186Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T10:10:13.585707Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T10:10:13.586812Z","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-20T10:10:13.590013Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fe334179879fd0aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T10:10:13.591029Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fe334179879fd0aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T10:10:13.591725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"info","ts":"2026-04-20T10:10:13.591942Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","added-peer-id":"fe334179879fd0aa","added-peer-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-20T10:10:13.59035Z","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-20T10:10:13.592857Z","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-20T10:10:13.59288Z","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-20T10:10:13.596178Z","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-20T10:10:13.596584Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fe334179879fd0aa","initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T10:10:13.596716Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T10:10:13.596765Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-20T10:10:13.59695Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-20T10:10:14.451894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T10:10:14.451953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T10:10:14.451975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgPreVoteResp from fe334179879fd0aa at term 1"} {"level":"info","ts":"2026-04-20T10:10:14.451993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became candidate at term 2"} {"level":"info","ts":"2026-04-20T10:10:14.45201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgVoteResp from fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-20T10:10:14.452026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became leader at term 2"} {"level":"info","ts":"2026-04-20T10:10:14.452043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fe334179879fd0aa elected leader fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-20T10:10:14.452866Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:10:14.453887Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:10:14.453987Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fe334179879fd0aa","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.69:2379]}","request-path":"/0/members/fe334179879fd0aa/attributes","cluster-id":"d79a8cccc23ced73","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T10:10:14.454005Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:10:14.454062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:10:14.454136Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:10:14.454198Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:10:14.454333Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T10:10:14.454432Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T10:10:14.455748Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T10:10:14.458337Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.69:2379"} {"level":"warn","ts":"2026-04-20T10:10:17.103325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.697162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:10:17.103453Z","caller":"traceutil/trace.go:171","msg":"trace[1815035079] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"122.2326ms","start":"2026-04-20T10:10:16.981201Z","end":"2026-04-20T10:10:17.103433Z","steps":["trace[1815035079] 'compare' (duration: 115.556858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:10:18.508793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001373296s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:10:18.508916Z","caller":"traceutil/trace.go:171","msg":"trace[1424196502] linearizableReadLoop","detail":"{readStateIndex:151; appliedIndex:150; }","duration":"556.338143ms","start":"2026-04-20T10:10:17.952559Z","end":"2026-04-20T10:10:18.508897Z","steps":["trace[1424196502] 'read index received' (duration: 39.011µs)","trace[1424196502] 'applied index is now lower than readState.Index' (duration: 556.297602ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:10:18.509075Z","caller":"traceutil/trace.go:171","msg":"trace[1751750042] transaction","detail":"{read_only:false; response_revision:146; number_of_response:1; }","duration":"1.335372566s","start":"2026-04-20T10:10:17.17367Z","end":"2026-04-20T10:10:18.509042Z","steps":["trace[1751750042] 'process raft request' (duration: 333.674ms)","trace[1751750042] 'compare' (duration: 1.00119852s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:10:18.509159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.56278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:10:18.509198Z","caller":"traceutil/trace.go:171","msg":"trace[759201225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:146; }","duration":"556.671774ms","start":"2026-04-20T10:10:17.952515Z","end":"2026-04-20T10:10:18.509187Z","steps":["trace[759201225] 'agreement among raft nodes before linearized reading' (duration: 556.510069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:10:18.509241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:10:17.952495Z","time spent":"556.738405ms","remote":"127.0.0.1:41658","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-20T10:10:18.509201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:10:17.173651Z","time spent":"1.33550292s","remote":"127.0.0.1:42040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":685,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:10:19.01Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003660985380968,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:10:19.424376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.739826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:10:19.424526Z","caller":"traceutil/trace.go:171","msg":"trace[401227409] linearizableReadLoop","detail":"{readStateIndex:152; appliedIndex:151; }","duration":"915.500924ms","start":"2026-04-20T10:10:18.508998Z","end":"2026-04-20T10:10:19.424499Z","steps":["trace[401227409] 'read index received' (duration: 594.486129ms)","trace[401227409] 'applied index is now lower than readState.Index' (duration: 321.013225ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:10:19.424632Z","caller":"traceutil/trace.go:171","msg":"trace[1862069908] transaction","detail":"{read_only:false; response_revision:147; number_of_response:1; }","duration":"1.116501374s","start":"2026-04-20T10:10:18.308072Z","end":"2026-04-20T10:10:19.424574Z","steps":["trace[1862069908] 'process raft request' (duration: 795.468658ms)","trace[1862069908] 'compare' (duration: 320.54505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:10:19.424722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.208033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:10:19.424758Z","caller":"traceutil/trace.go:171","msg":"trace[116180857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:147; }","duration":"914.283946ms","start":"2026-04-20T10:10:18.510461Z","end":"2026-04-20T10:10:19.424745Z","steps":["trace[116180857] 'agreement among raft nodes before linearized reading' (duration: 914.175043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:10:19.424799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:10:18.510445Z","time spent":"914.344968ms","remote":"127.0.0.1:41670","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-20T10:10:19.424826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:10:18.308037Z","time spent":"1.11669678s","remote":"127.0.0.1:41858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:10:19.424813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451097698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:10:19.424904Z","caller":"traceutil/trace.go:171","msg":"trace[1773632402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:147; }","duration":"1.451244912s","start":"2026-04-20T10:10:17.973633Z","end":"2026-04-20T10:10:19.424878Z","steps":["trace[1773632402] 'agreement among raft nodes before linearized reading' (duration: 1.451008685s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:10:19.424955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:10:17.973603Z","time spent":"1.451341495s","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T10:10:19.424918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.663155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:10:19.425085Z","caller":"traceutil/trace.go:171","msg":"trace[171582359] range","detail":"{range_begin:/registry/clusterroles/system:controller:cronjob-controller; range_end:; response_count:0; response_revision:147; }","duration":"912.880642ms","start":"2026-04-20T10:10:18.51217Z","end":"2026-04-20T10:10:19.425051Z","steps":["trace[171582359] 'agreement among raft nodes before linearized reading' (duration: 912.581863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:10:19.425136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:10:18.512152Z","time spent":"912.976404ms","remote":"127.0.0.1:42040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":29,"request content":"key:\"/registry/clusterroles/system:controller:cronjob-controller\" "} {"level":"warn","ts":"2026-04-20T10:10:19.424808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.011846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:10:19.425224Z","caller":"traceutil/trace.go:171","msg":"trace[319588298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:147; }","duration":"327.505791ms","start":"2026-04-20T10:10:19.097704Z","end":"2026-04-20T10:10:19.425209Z","steps":["trace[319588298] 'agreement among raft nodes before linearized reading' (duration: 327.003775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:10:19.426636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:10:19.097679Z","time spent":"327.617455ms","remote":"127.0.0.1:41658","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-20T10:10:20.334042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.225859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"info","ts":"2026-04-20T10:10:20.334147Z","caller":"traceutil/trace.go:171","msg":"trace[624959251] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:237; }","duration":"285.401615ms","start":"2026-04-20T10:10:20.048727Z","end":"2026-04-20T10:10:20.334128Z","steps":["trace[624959251] 'range keys from in-memory index tree' (duration: 285.090334ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:20.335074Z","caller":"traceutil/trace.go:171","msg":"trace[1966476899] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"234.41727ms","start":"2026-04-20T10:10:20.100619Z","end":"2026-04-20T10:10:20.335037Z","steps":["trace[1966476899] 'process raft request' (duration: 234.250925ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:27.623484Z","caller":"traceutil/trace.go:171","msg":"trace[1930882639] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"128.984631ms","start":"2026-04-20T10:10:27.494466Z","end":"2026-04-20T10:10:27.623451Z","steps":["trace[1930882639] 'process raft request' (duration: 128.776354ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:32.704946Z","caller":"traceutil/trace.go:171","msg":"trace[828600697] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"144.177122ms","start":"2026-04-20T10:10:32.560743Z","end":"2026-04-20T10:10:32.70492Z","steps":["trace[828600697] 'process raft request' (duration: 143.980135ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:32.859609Z","caller":"traceutil/trace.go:171","msg":"trace[663354550] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"142.360495ms","start":"2026-04-20T10:10:32.717226Z","end":"2026-04-20T10:10:32.859586Z","steps":["trace[663354550] 'process raft request' (duration: 139.030068ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:33.080967Z","caller":"traceutil/trace.go:171","msg":"trace[69361147] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"141.414856ms","start":"2026-04-20T10:10:32.939528Z","end":"2026-04-20T10:10:33.080943Z","steps":["trace[69361147] 'process raft request' (duration: 136.840859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:10:34.218718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.268778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:10:34.218844Z","caller":"traceutil/trace.go:171","msg":"trace[1505954143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"208.467744ms","start":"2026-04-20T10:10:34.01035Z","end":"2026-04-20T10:10:34.218818Z","steps":["trace[1505954143] 'range keys from in-memory index tree' (duration: 208.178384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:10:34.218855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.206332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T10:10:34.218978Z","caller":"traceutil/trace.go:171","msg":"trace[1579821789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:425; }","duration":"391.395568ms","start":"2026-04-20T10:10:33.827555Z","end":"2026-04-20T10:10:34.21895Z","steps":["trace[1579821789] 'range keys from in-memory index tree' (duration: 391.038586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:10:34.219028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:10:33.827533Z","time spent":"391.486721ms","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T10:10:57.442043Z","caller":"traceutil/trace.go:171","msg":"trace[1235531650] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"138.053378ms","start":"2026-04-20T10:10:57.303884Z","end":"2026-04-20T10:10:57.441937Z","steps":["trace[1235531650] 'process raft request' (duration: 137.839511ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:57.578936Z","caller":"traceutil/trace.go:171","msg":"trace[621044797] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"132.22559ms","start":"2026-04-20T10:10:57.446671Z","end":"2026-04-20T10:10:57.578897Z","steps":["trace[621044797] 'process raft request' (duration: 124.501462ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:02.672515Z","caller":"traceutil/trace.go:171","msg":"trace[1009834521] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"244.453785ms","start":"2026-04-20T10:11:02.428041Z","end":"2026-04-20T10:11:02.672495Z","steps":["trace[1009834521] 'process raft request' (duration: 244.32157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:05.572014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.062984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:11:05.572092Z","caller":"traceutil/trace.go:171","msg":"trace[319094108] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"292.427446ms","start":"2026-04-20T10:11:05.279653Z","end":"2026-04-20T10:11:05.57208Z","steps":["trace[319094108] 'read index received' (duration: 155.203739ms)","trace[319094108] 'applied index is now lower than readState.Index' (duration: 137.222777ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:11:05.572345Z","caller":"traceutil/trace.go:171","msg":"trace[632543756] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"313.719ms","start":"2026-04-20T10:11:05.258581Z","end":"2026-04-20T10:11:05.5723Z","steps":["trace[632543756] 'process raft request' (duration: 176.180358ms)","trace[632543756] 'compare' (duration: 136.947302ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:05.572432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.774742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4671"} {"level":"info","ts":"2026-04-20T10:11:05.57246Z","caller":"traceutil/trace.go:171","msg":"trace[327757773] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:558; }","duration":"292.834094ms","start":"2026-04-20T10:11:05.279616Z","end":"2026-04-20T10:11:05.572451Z","steps":["trace[327757773] 'agreement among raft nodes before linearized reading' (duration: 292.688431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:05.57247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.229236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-04-20T10:11:05.572475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.786076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-20T10:11:05.572523Z","caller":"traceutil/trace.go:171","msg":"trace[98042027] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:558; }","duration":"256.311688ms","start":"2026-04-20T10:11:05.316195Z","end":"2026-04-20T10:11:05.572507Z","steps":["trace[98042027] 'agreement among raft nodes before linearized reading' (duration: 256.216386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:05.572532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:05.258563Z","time spent":"313.874203ms","remote":"127.0.0.1:41858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4664,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:11:05.57255Z","caller":"traceutil/trace.go:171","msg":"trace[1715671523] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:558; }","duration":"256.898478ms","start":"2026-04-20T10:11:05.315627Z","end":"2026-04-20T10:11:05.572526Z","steps":["trace[1715671523] 'agreement among raft nodes before linearized reading' (duration: 256.728285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:05.572702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.132875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-04-20T10:11:05.572777Z","caller":"traceutil/trace.go:171","msg":"trace[2045202831] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:558; }","duration":"256.215576ms","start":"2026-04-20T10:11:05.316541Z","end":"2026-04-20T10:11:05.572756Z","steps":["trace[2045202831] 'agreement among raft nodes before linearized reading' (duration: 255.85836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:05.79561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.571121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-04-20T10:11:05.795679Z","caller":"traceutil/trace.go:171","msg":"trace[1640982798] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:560; }","duration":"104.681243ms","start":"2026-04-20T10:11:05.69098Z","end":"2026-04-20T10:11:05.795662Z","steps":["trace[1640982798] 'range keys from in-memory index tree' (duration: 104.313716ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:06.870365Z","caller":"traceutil/trace.go:171","msg":"trace[108337771] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"109.668164ms","start":"2026-04-20T10:11:06.760662Z","end":"2026-04-20T10:11:06.87033Z","steps":["trace[108337771] 'process raft request' (duration: 109.459009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:07.974545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004016287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:11:07.974745Z","caller":"traceutil/trace.go:171","msg":"trace[799373028] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:564; }","duration":"1.004273202s","start":"2026-04-20T10:11:06.970448Z","end":"2026-04-20T10:11:07.974721Z","steps":["trace[799373028] 'range keys from in-memory index tree' (duration: 1.003941596s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:07.975154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:06.970421Z","time spent":"1.00471256s","remote":"127.0.0.1:41778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-20T10:11:07.974602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.001173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:11:07.975384Z","caller":"traceutil/trace.go:171","msg":"trace[1171099435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"967.769478ms","start":"2026-04-20T10:11:07.00755Z","end":"2026-04-20T10:11:07.97532Z","steps":["trace[1171099435] 'range keys from in-memory index tree' (duration: 966.923832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:07.975483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:07.00753Z","time spent":"967.91833ms","remote":"127.0.0.1:41670","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-20T10:11:07.981687Z","caller":"traceutil/trace.go:171","msg":"trace[1681370755] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"667.034073ms","start":"2026-04-20T10:11:07.314625Z","end":"2026-04-20T10:11:07.981659Z","steps":["trace[1681370755] 'process raft request' (duration: 666.710758ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:07.981725Z","caller":"traceutil/trace.go:171","msg":"trace[1063116326] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"362.957687ms","start":"2026-04-20T10:11:07.618743Z","end":"2026-04-20T10:11:07.981701Z","steps":["trace[1063116326] 'read index received' (duration: 359.963082ms)","trace[1063116326] 'applied index is now lower than readState.Index' (duration: 2.993295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:07.981897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:07.3146Z","time spent":"667.175186ms","remote":"127.0.0.1:41950","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-20T10:11:07.981943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.819275ms","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-20T10:11:07.981991Z","caller":"traceutil/trace.go:171","msg":"trace[1250022064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:565; }","duration":"325.918828ms","start":"2026-04-20T10:11:07.656059Z","end":"2026-04-20T10:11:07.981978Z","steps":["trace[1250022064] 'agreement among raft nodes before linearized reading' (duration: 325.772894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:07.982024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:07.656034Z","time spent":"325.981988ms","remote":"127.0.0.1:41950","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-20T10:11:07.982005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.236722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T10:11:07.982098Z","caller":"traceutil/trace.go:171","msg":"trace[492938342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"363.393495ms","start":"2026-04-20T10:11:07.618693Z","end":"2026-04-20T10:11:07.982087Z","steps":["trace[492938342] 'agreement among raft nodes before linearized reading' (duration: 363.12955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:07.982144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:07.618673Z","time spent":"363.463236ms","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T10:11:08.930942Z","caller":"traceutil/trace.go:171","msg":"trace[630785154] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"117.510672ms","start":"2026-04-20T10:11:08.813401Z","end":"2026-04-20T10:11:08.930912Z","steps":["trace[630785154] 'process raft request' (duration: 111.244535ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:08.931064Z","caller":"traceutil/trace.go:171","msg":"trace[547484666] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"112.58271ms","start":"2026-04-20T10:11:08.818466Z","end":"2026-04-20T10:11:08.931049Z","steps":["trace[547484666] 'process raft request' (duration: 112.390447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:15.463665Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003660985382062,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:11:15.964018Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003660985382062,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:11:16.465067Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003660985382062,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:11:16.515221Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.698471001s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T10:11:16.517023Z","caller":"traceutil/trace.go:171","msg":"trace[1398713411] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"1.701195665s","start":"2026-04-20T10:11:14.815797Z","end":"2026-04-20T10:11:16.516993Z","steps":["trace[1398713411] 'process raft request' (duration: 1.699827358s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:16.517184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:14.815772Z","time spent":"1.701316937s","remote":"127.0.0.1:41778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:11:17.008534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000427003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T10:11:17.008643Z","caller":"traceutil/trace.go:171","msg":"trace[1563125929] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000581915s","start":"2026-04-20T10:11:15.008042Z","end":"2026-04-20T10:11:17.008624Z","steps":["trace[1563125929] 'agreement among raft nodes before linearized reading' (duration: 2.000449533s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:17.008709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:15.008029Z","time spent":"2.000669747s","remote":"127.0.0.1:41670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:11:17.695799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149141419s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-20T10:11:17.697711Z","caller":"traceutil/trace.go:171","msg":"trace[664010082] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"1.122989766s","start":"2026-04-20T10:11:16.574216Z","end":"2026-04-20T10:11:17.697205Z","steps":["trace[664010082] 'process raft request' (duration: 1.12169971s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:17.69796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:16.574191Z","time spent":"1.123629189s","remote":"127.0.0.1:42010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:11:17.698801Z","caller":"traceutil/trace.go:171","msg":"trace[599629641] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:695; }","duration":"2.735689293s","start":"2026-04-20T10:11:14.962936Z","end":"2026-04-20T10:11:17.698625Z","steps":["trace[599629641] 'read index received' (duration: 1.552577065s)","trace[599629641] 'applied index is now lower than readState.Index' (duration: 1.183105238s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:17.930176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.967218639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T10:11:17.930269Z","caller":"traceutil/trace.go:171","msg":"trace[635130706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:668; }","duration":"2.967351333s","start":"2026-04-20T10:11:14.962892Z","end":"2026-04-20T10:11:17.930243Z","steps":["trace[635130706] 'agreement among raft nodes before linearized reading' (duration: 2.735820445s)","trace[635130706] 'range keys from in-memory index tree' (duration: 231.139169ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:17.930319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:14.962874Z","time spent":"2.967434394s","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T10:11:17.930869Z","caller":"traceutil/trace.go:171","msg":"trace[2062179992] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"231.849513ms","start":"2026-04-20T10:11:17.698943Z","end":"2026-04-20T10:11:17.930792Z","steps":["trace[2062179992] 'read index received' (duration: 162.792392ms)","trace[2062179992] 'applied index is now lower than readState.Index' (duration: 69.055131ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:17.931072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.908238203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T10:11:17.931147Z","caller":"traceutil/trace.go:171","msg":"trace[1682477595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:668; }","duration":"1.908346394s","start":"2026-04-20T10:11:16.022781Z","end":"2026-04-20T10:11:17.931127Z","steps":["trace[1682477595] 'agreement among raft nodes before linearized reading' (duration: 1.908142331s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:17.931139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.647488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:11:17.931194Z","caller":"traceutil/trace.go:171","msg":"trace[1649916081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"914.702409ms","start":"2026-04-20T10:11:17.016476Z","end":"2026-04-20T10:11:17.931179Z","steps":["trace[1649916081] 'agreement among raft nodes before linearized reading' (duration: 914.537246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:17.931186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:16.022768Z","time spent":"1.908411465s","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T10:11:17.931239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:17.01645Z","time spent":"914.78045ms","remote":"127.0.0.1:41658","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-20T10:11:17.931293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.905904081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bktt5\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-20T10:11:17.931352Z","caller":"traceutil/trace.go:171","msg":"trace[73595433] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bktt5; range_end:; response_count:1; response_revision:668; }","duration":"2.905999053s","start":"2026-04-20T10:11:15.025335Z","end":"2026-04-20T10:11:17.931334Z","steps":["trace[73595433] 'agreement among raft nodes before linearized reading' (duration: 2.905774599s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:17.931391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:15.025316Z","time spent":"2.906067784s","remote":"127.0.0.1:41858","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-bktt5\" "} {"level":"warn","ts":"2026-04-20T10:11:17.931745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.914092129s","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-20T10:11:17.931777Z","caller":"traceutil/trace.go:171","msg":"trace[289915342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:668; }","duration":"1.914228012s","start":"2026-04-20T10:11:16.017541Z","end":"2026-04-20T10:11:17.931769Z","steps":["trace[289915342] 'agreement among raft nodes before linearized reading' (duration: 1.913979757s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:17.931797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:16.017519Z","time spent":"1.914272784s","remote":"127.0.0.1:41950","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-20T10:11:17.930912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:17.012448Z","time spent":"918.459764ms","remote":"127.0.0.1:41760","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-20T10:11:17.932231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.907669131s","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-20T10:11:17.932277Z","caller":"traceutil/trace.go:171","msg":"trace[1277149888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:668; }","duration":"1.907747633s","start":"2026-04-20T10:11:16.024518Z","end":"2026-04-20T10:11:17.932265Z","steps":["trace[1277149888] 'agreement among raft nodes before linearized reading' (duration: 1.906310454s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:17.932369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:16.024507Z","time spent":"1.907792533s","remote":"127.0.0.1:41950","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-20T10:11:17.931582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.546324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:11:17.93477Z","caller":"traceutil/trace.go:171","msg":"trace[2052336314] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:668; }","duration":"151.81169ms","start":"2026-04-20T10:11:17.782898Z","end":"2026-04-20T10:11:17.93471Z","steps":["trace[2052336314] 'agreement among raft nodes before linearized reading' (duration: 148.557835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:19.043253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.685224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:11:19.043348Z","caller":"traceutil/trace.go:171","msg":"trace[1950750993] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"1.108863656s","start":"2026-04-20T10:11:17.934469Z","end":"2026-04-20T10:11:19.043333Z","steps":["trace[1950750993] 'process raft request' (duration: 783.02589ms)","trace[1950750993] 'compare' (duration: 325.588072ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:19.043389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:17.934456Z","time spent":"1.108913688s","remote":"127.0.0.1:41950","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-20T10:11:19.054715Z","caller":"traceutil/trace.go:171","msg":"trace[1527964899] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:700; }","duration":"252.115101ms","start":"2026-04-20T10:11:18.802572Z","end":"2026-04-20T10:11:19.054687Z","steps":["trace[1527964899] 'read index received' (duration: 250.155521ms)","trace[1527964899] 'applied index is now lower than readState.Index' (duration: 1.95835ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:11:19.054849Z","caller":"traceutil/trace.go:171","msg":"trace[1992941472] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"1.120091718s","start":"2026-04-20T10:11:17.934745Z","end":"2026-04-20T10:11:19.054836Z","steps":["trace[1992941472] 'process raft request' (duration: 1.118072436s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:19.05557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:17.934727Z","time spent":"1.120777702s","remote":"127.0.0.1:41760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:11:19.055128Z","caller":"traceutil/trace.go:171","msg":"trace[704068538] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"1.097138586s","start":"2026-04-20T10:11:17.957953Z","end":"2026-04-20T10:11:19.055092Z","steps":["trace[704068538] 'process raft request' (duration: 1.095152425s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:19.05568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:17.957935Z","time spent":"1.097691647s","remote":"127.0.0.1:41858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:11:19.055145Z","caller":"traceutil/trace.go:171","msg":"trace[1604666371] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"722.664923ms","start":"2026-04-20T10:11:18.332447Z","end":"2026-04-20T10:11:19.055112Z","steps":["trace[1604666371] 'process raft request' (duration: 721.004429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:19.055808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:18.33242Z","time spent":"723.366678ms","remote":"127.0.0.1:41778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:11:19.055258Z","caller":"traceutil/trace.go:171","msg":"trace[173108318] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"1.119151009s","start":"2026-04-20T10:11:17.936091Z","end":"2026-04-20T10:11:19.055242Z","steps":["trace[173108318] 'process raft request' (duration: 1.116946883s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:19.055939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:17.936081Z","time spent":"1.119836952s","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:11:19.05529Z","caller":"traceutil/trace.go:171","msg":"trace[449597969] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"1.119623399s","start":"2026-04-20T10:11:17.935634Z","end":"2026-04-20T10:11:19.055257Z","steps":["trace[449597969] 'process raft request' (duration: 1.117342471s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:19.055271Z","caller":"traceutil/trace.go:171","msg":"trace[1709562314] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"967.670602ms","start":"2026-04-20T10:11:18.087552Z","end":"2026-04-20T10:11:19.055223Z","steps":["trace[1709562314] 'process raft request' (duration: 965.603681ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:19.05531Z","caller":"traceutil/trace.go:171","msg":"trace[1875837602] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"1.114210389s","start":"2026-04-20T10:11:17.941037Z","end":"2026-04-20T10:11:19.055248Z","steps":["trace[1875837602] 'process raft request' (duration: 1.112031854s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:19.058287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:17.941021Z","time spent":"1.117231521s","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:11:19.057797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:17.935626Z","time spent":"1.122054598s","remote":"127.0.0.1:41950","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-20T10:11:19.057941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:18.087526Z","time spent":"970.322076ms","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:11:19.058777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.018319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:11:19.058925Z","caller":"traceutil/trace.go:171","msg":"trace[885793259] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:676; }","duration":"256.353298ms","start":"2026-04-20T10:11:18.802542Z","end":"2026-04-20T10:11:19.058896Z","steps":["trace[885793259] 'agreement among raft nodes before linearized reading' (duration: 252.56894ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:20.195576Z","caller":"traceutil/trace.go:171","msg":"trace[1300201772] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"141.717625ms","start":"2026-04-20T10:11:20.053821Z","end":"2026-04-20T10:11:20.195539Z","steps":["trace[1300201772] 'process raft request' (duration: 135.841574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:20.928933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.477289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:11:20.929046Z","caller":"traceutil/trace.go:171","msg":"trace[1476560345] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:686; }","duration":"328.610212ms","start":"2026-04-20T10:11:20.600411Z","end":"2026-04-20T10:11:20.929022Z","steps":["trace[1476560345] 'count revisions from in-memory index tree' (duration: 328.395238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:20.929322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:20.600368Z","time spent":"328.930728ms","remote":"127.0.0.1:41996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:11:20.932477Z","caller":"traceutil/trace.go:171","msg":"trace[1435524580] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"219.885489ms","start":"2026-04-20T10:11:20.712558Z","end":"2026-04-20T10:11:20.932444Z","steps":["trace[1435524580] 'process raft request' (duration: 219.234315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:21.195479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.275647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bktt5\" ","response":"range_response_count:1 size:17243"} {"level":"info","ts":"2026-04-20T10:11:21.19556Z","caller":"traceutil/trace.go:171","msg":"trace[1444177068] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bktt5; range_end:; response_count:1; response_revision:690; }","duration":"152.391649ms","start":"2026-04-20T10:11:21.043149Z","end":"2026-04-20T10:11:21.19554Z","steps":["trace[1444177068] 'range keys from in-memory index tree' (duration: 152.110633ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:21.282Z","caller":"traceutil/trace.go:171","msg":"trace[982417833] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"238.067892ms","start":"2026-04-20T10:11:21.043911Z","end":"2026-04-20T10:11:21.281979Z","steps":["trace[982417833] 'process raft request' (duration: 237.955389ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:21.28269Z","caller":"traceutil/trace.go:171","msg":"trace[846610967] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:723; }","duration":"234.510207ms","start":"2026-04-20T10:11:21.048154Z","end":"2026-04-20T10:11:21.282664Z","steps":["trace[846610967] 'read index received' (duration: 234.503207ms)","trace[846610967] 'applied index is now lower than readState.Index' (duration: 5.9µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:21.282854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.694071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T10:11:21.282888Z","caller":"traceutil/trace.go:171","msg":"trace[986515305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:691; }","duration":"234.769512ms","start":"2026-04-20T10:11:21.048109Z","end":"2026-04-20T10:11:21.282879Z","steps":["trace[986515305] 'agreement among raft nodes before linearized reading' (duration: 234.629519ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:21.385738Z","caller":"traceutil/trace.go:171","msg":"trace[1419537662] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"177.163105ms","start":"2026-04-20T10:11:21.208554Z","end":"2026-04-20T10:11:21.385717Z","steps":["trace[1419537662] 'process raft request' (duration: 171.537118ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:21.385813Z","caller":"traceutil/trace.go:171","msg":"trace[650040469] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:723; }","duration":"103.015182ms","start":"2026-04-20T10:11:21.282771Z","end":"2026-04-20T10:11:21.385787Z","steps":["trace[650040469] 'read index received' (duration: 97.333264ms)","trace[650040469] 'applied index is now lower than readState.Index' (duration: 5.677458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:21.386066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.43576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T10:11:21.386128Z","caller":"traceutil/trace.go:171","msg":"trace[2003194001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"326.549751ms","start":"2026-04-20T10:11:21.059566Z","end":"2026-04-20T10:11:21.386116Z","steps":["trace[2003194001] 'agreement among raft nodes before linearized reading' (duration: 326.301747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:21.386172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.059548Z","time spent":"326.613723ms","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T10:11:21.386236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.116744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-20T10:11:21.386271Z","caller":"traceutil/trace.go:171","msg":"trace[735456446] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:694; }","duration":"240.174325ms","start":"2026-04-20T10:11:21.146086Z","end":"2026-04-20T10:11:21.386261Z","steps":["trace[735456446] 'agreement among raft nodes before linearized reading' (duration: 240.124324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:21.386084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.356864ms","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-20T10:11:21.386308Z","caller":"traceutil/trace.go:171","msg":"trace[409759220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"322.62156ms","start":"2026-04-20T10:11:21.063677Z","end":"2026-04-20T10:11:21.386299Z","steps":["trace[409759220] 'agreement among raft nodes before linearized reading' (duration: 322.296854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:21.386346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.063659Z","time spent":"322.678131ms","remote":"127.0.0.1:41950","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-20T10:11:21.386649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.792784ms","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-20T10:11:21.386685Z","caller":"traceutil/trace.go:171","msg":"trace[1571647704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"322.851484ms","start":"2026-04-20T10:11:21.063825Z","end":"2026-04-20T10:11:21.386677Z","steps":["trace[1571647704] 'agreement among raft nodes before linearized reading' (duration: 322.074088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:21.386708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.063817Z","time spent":"322.884645ms","remote":"127.0.0.1:41950","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-20T10:11:21.895399Z","caller":"traceutil/trace.go:171","msg":"trace[567329567] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:731; }","duration":"451.462539ms","start":"2026-04-20T10:11:21.443917Z","end":"2026-04-20T10:11:21.89538Z","steps":["trace[567329567] 'read index received' (duration: 450.789615ms)","trace[567329567] 'applied index is now lower than readState.Index' (duration: 672.154µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:11:21.895478Z","caller":"traceutil/trace.go:171","msg":"trace[783218402] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"469.045185ms","start":"2026-04-20T10:11:21.426405Z","end":"2026-04-20T10:11:21.89545Z","steps":["trace[783218402] 'process raft request' (duration: 468.476024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:21.895584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.658204ms","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-20T10:11:21.895619Z","caller":"traceutil/trace.go:171","msg":"trace[485360305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:700; }","duration":"451.734816ms","start":"2026-04-20T10:11:21.443873Z","end":"2026-04-20T10:11:21.895608Z","steps":["trace[485360305] 'agreement among raft nodes before linearized reading' (duration: 451.585602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:21.895642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.426385Z","time spent":"469.164159ms","remote":"127.0.0.1:41778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:11:21.89575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.443852Z","time spent":"451.784786ms","remote":"127.0.0.1:41950","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-20T10:11:22.011116Z","caller":"traceutil/trace.go:171","msg":"trace[909465933] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"433.302202ms","start":"2026-04-20T10:11:21.577789Z","end":"2026-04-20T10:11:22.011091Z","steps":["trace[909465933] 'process raft request' (duration: 417.021292ms)","trace[909465933] 'compare' (duration: 15.620506ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:11:22.011143Z","caller":"traceutil/trace.go:171","msg":"trace[173594764] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"115.665157ms","start":"2026-04-20T10:11:21.895458Z","end":"2026-04-20T10:11:22.011123Z","steps":["trace[173594764] 'read index received' (duration: 99.355326ms)","trace[173594764] 'applied index is now lower than readState.Index' (duration: 16.307261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:22.011219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.157272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:11:22.011246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.577768Z","time spent":"433.432055ms","remote":"127.0.0.1:41760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:11:22.011273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.761537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:11:22.011256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.36896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:11:22.011283Z","caller":"traceutil/trace.go:171","msg":"trace[1261443007] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"524.253175ms","start":"2026-04-20T10:11:21.487012Z","end":"2026-04-20T10:11:22.011265Z","steps":["trace[1261443007] 'agreement among raft nodes before linearized reading' (duration: 524.156553ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.011306Z","caller":"traceutil/trace.go:171","msg":"trace[1513296662] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"565.839908ms","start":"2026-04-20T10:11:21.445454Z","end":"2026-04-20T10:11:22.011294Z","steps":["trace[1513296662] 'agreement among raft nodes before linearized reading' (duration: 565.759217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:22.011313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.022933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:11:22.011362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.483482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:11:22.011397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.763734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:11:22.0114Z","caller":"traceutil/trace.go:171","msg":"trace[1470350888] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"553.520003ms","start":"2026-04-20T10:11:21.457872Z","end":"2026-04-20T10:11:22.011392Z","steps":["trace[1470350888] 'agreement among raft nodes before linearized reading' (duration: 553.471252ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.011423Z","caller":"traceutil/trace.go:171","msg":"trace[263953937] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"523.805915ms","start":"2026-04-20T10:11:21.487608Z","end":"2026-04-20T10:11:22.011414Z","steps":["trace[263953937] 'agreement among raft nodes before linearized reading' (duration: 523.770604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:22.01142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.189386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:11:22.011413Z","caller":"traceutil/trace.go:171","msg":"trace[1386846844] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"516.166605ms","start":"2026-04-20T10:11:21.495219Z","end":"2026-04-20T10:11:22.011386Z","steps":["trace[1386846844] 'agreement among raft nodes before linearized reading' (duration: 515.970611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:22.011438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.564973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:11:22.01145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.487595Z","time spent":"523.849865ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-04-20T10:11:22.011323Z","caller":"traceutil/trace.go:171","msg":"trace[234877399] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"553.444251ms","start":"2026-04-20T10:11:21.45786Z","end":"2026-04-20T10:11:22.011305Z","steps":["trace[234877399] 'agreement among raft nodes before linearized reading' (duration: 553.333959ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.011478Z","caller":"traceutil/trace.go:171","msg":"trace[1656828575] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"365.278488ms","start":"2026-04-20T10:11:21.646181Z","end":"2026-04-20T10:11:22.011459Z","steps":["trace[1656828575] 'agreement among raft nodes before linearized reading' (duration: 365.207917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:22.011489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.495198Z","time spent":"516.279868ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-20T10:11:22.011483Z","caller":"traceutil/trace.go:171","msg":"trace[1067946196] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"553.642095ms","start":"2026-04-20T10:11:21.45783Z","end":"2026-04-20T10:11:22.011472Z","steps":["trace[1067946196] 'agreement among raft nodes before linearized reading' (duration: 553.556093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:22.011513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.691076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:11:22.011527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.646159Z","time spent":"365.354959ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-20T10:11:22.011548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.457827Z","time spent":"553.710017ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-04-20T10:11:22.011551Z","caller":"traceutil/trace.go:171","msg":"trace[2097766856] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"553.749727ms","start":"2026-04-20T10:11:21.457789Z","end":"2026-04-20T10:11:22.011538Z","steps":["trace[2097766856] 'agreement among raft nodes before linearized reading' (duration: 553.692806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:22.011539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.457858Z","time spent":"553.655945ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-04-20T10:11:22.011585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.457775Z","time spent":"553.802449ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-04-20T10:11:22.011362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.308005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:11:22.011365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.445442Z","time spent":"565.91261ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-04-20T10:11:22.011327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.39578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:11:22.011319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.486984Z","time spent":"524.327486ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-04-20T10:11:22.011632Z","caller":"traceutil/trace.go:171","msg":"trace[937883376] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"523.584151ms","start":"2026-04-20T10:11:21.488037Z","end":"2026-04-20T10:11:22.011621Z","steps":["trace[937883376] 'agreement among raft nodes before linearized reading' (duration: 523.302685ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.011682Z","caller":"traceutil/trace.go:171","msg":"trace[452169691] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:703; }","duration":"553.790659ms","start":"2026-04-20T10:11:21.457879Z","end":"2026-04-20T10:11:22.011669Z","steps":["trace[452169691] 'agreement among raft nodes before linearized reading' (duration: 553.33395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:22.011707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.488032Z","time spent":"523.667582ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-04-20T10:11:22.011716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.457876Z","time spent":"553.830719ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-20T10:11:22.011443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:21.457864Z","time spent":"553.571504ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-04-20T10:11:22.011127Z","caller":"traceutil/trace.go:171","msg":"trace[220894245] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"111.421728ms","start":"2026-04-20T10:11:21.899667Z","end":"2026-04-20T10:11:22.011089Z","steps":["trace[220894245] 'process raft request' (duration: 110.917227ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.531285Z","caller":"traceutil/trace.go:171","msg":"trace[381416778] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"283.116092ms","start":"2026-04-20T10:11:22.248119Z","end":"2026-04-20T10:11:22.531235Z","steps":["trace[381416778] 'process raft request' (duration: 283.009209ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:22.835112Z","caller":"traceutil/trace.go:171","msg":"trace[959088199] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"385.780422ms","start":"2026-04-20T10:11:22.449301Z","end":"2026-04-20T10:11:22.835081Z","steps":["trace[959088199] 'process raft request' (duration: 326.03589ms)","trace[959088199] 'compare' (duration: 59.603169ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:22.835256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.449267Z","time spent":"385.922157ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:11:23.010998Z","caller":"traceutil/trace.go:171","msg":"trace[355942068] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"363.418594ms","start":"2026-04-20T10:11:22.647558Z","end":"2026-04-20T10:11:23.010976Z","steps":["trace[355942068] 'process raft request' (duration: 363.384384ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:23.011215Z","caller":"traceutil/trace.go:171","msg":"trace[1656959806] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"476.457064ms","start":"2026-04-20T10:11:22.534693Z","end":"2026-04-20T10:11:23.01115Z","steps":["trace[1656959806] 'process raft request' (duration: 476.170748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:23.011415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.534668Z","time spent":"476.637997ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:11:23.011501Z","caller":"traceutil/trace.go:171","msg":"trace[1602399666] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"476.379973ms","start":"2026-04-20T10:11:22.534558Z","end":"2026-04-20T10:11:23.010938Z","steps":["trace[1602399666] 'process raft request' (duration: 445.498585ms)","trace[1602399666] 'compare' (duration: 30.584211ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:23.011561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.647538Z","time spent":"363.609499ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:11:23.011794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:22.534529Z","time spent":"477.106567ms","remote":"127.0.0.1:42230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:11:23.040002Z","caller":"traceutil/trace.go:171","msg":"trace[984978037] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"137.805433ms","start":"2026-04-20T10:11:22.902177Z","end":"2026-04-20T10:11:23.039982Z","steps":["trace[984978037] 'process raft request' (duration: 137.729281ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:23.040278Z","caller":"traceutil/trace.go:171","msg":"trace[1793174716] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"178.959545ms","start":"2026-04-20T10:11:22.861284Z","end":"2026-04-20T10:11:23.040243Z","steps":["trace[1793174716] 'process raft request' (duration: 178.534086ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:23.23653Z","caller":"traceutil/trace.go:171","msg":"trace[228622059] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"138.39285ms","start":"2026-04-20T10:11:23.098111Z","end":"2026-04-20T10:11:23.236504Z","steps":["trace[228622059] 'read index received' (duration: 136.871648ms)","trace[228622059] 'applied index is now lower than readState.Index' (duration: 1.520632ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:11:23.236624Z","caller":"traceutil/trace.go:171","msg":"trace[605287913] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"187.371035ms","start":"2026-04-20T10:11:23.049204Z","end":"2026-04-20T10:11:23.236575Z","steps":["trace[605287913] 'process raft request' (duration: 185.758831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:23.236699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.150137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:11:23.236836Z","caller":"traceutil/trace.go:171","msg":"trace[1953454661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"194.374191ms","start":"2026-04-20T10:11:23.042437Z","end":"2026-04-20T10:11:23.236811Z","steps":["trace[1953454661] 'agreement among raft nodes before linearized reading' (duration: 194.133447ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:23.243106Z","caller":"traceutil/trace.go:171","msg":"trace[913166855] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"139.713758ms","start":"2026-04-20T10:11:23.103375Z","end":"2026-04-20T10:11:23.243089Z","steps":["trace[913166855] 'process raft request' (duration: 139.357131ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:23.243115Z","caller":"traceutil/trace.go:171","msg":"trace[1386330241] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"133.327273ms","start":"2026-04-20T10:11:23.109764Z","end":"2026-04-20T10:11:23.243092Z","steps":["trace[1386330241] 'process raft request' (duration: 133.156259ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:23.243148Z","caller":"traceutil/trace.go:171","msg":"trace[1158240468] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"111.969702ms","start":"2026-04-20T10:11:23.131151Z","end":"2026-04-20T10:11:23.24312Z","steps":["trace[1158240468] 'process raft request' (duration: 111.83181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:23.243692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.082858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bktt5\" ","response":"range_response_count:1 size:17786"} {"level":"info","ts":"2026-04-20T10:11:23.243734Z","caller":"traceutil/trace.go:171","msg":"trace[178729732] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bktt5; range_end:; response_count:1; response_revision:736; }","duration":"115.152119ms","start":"2026-04-20T10:11:23.12857Z","end":"2026-04-20T10:11:23.243722Z","steps":["trace[178729732] 'agreement among raft nodes before linearized reading' (duration: 114.68627ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:27.558091Z","caller":"traceutil/trace.go:171","msg":"trace[1384348538] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"104.331253ms","start":"2026-04-20T10:11:27.453719Z","end":"2026-04-20T10:11:27.55805Z","steps":["trace[1384348538] 'process raft request' (duration: 101.91353ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:28.153018Z","caller":"traceutil/trace.go:171","msg":"trace[107137861] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"113.774104ms","start":"2026-04-20T10:11:28.039208Z","end":"2026-04-20T10:11:28.152982Z","steps":["trace[107137861] 'process raft request' (duration: 113.65051ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:29.33455Z","caller":"traceutil/trace.go:171","msg":"trace[1611217102] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"101.732934ms","start":"2026-04-20T10:11:29.232794Z","end":"2026-04-20T10:11:29.334527Z","steps":["trace[1611217102] 'process raft request' (duration: 101.570671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:36.051706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003660985382477,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:11:36.552446Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003660985382477,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:11:37.015378Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.491413907s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T10:11:37.017121Z","caller":"traceutil/trace.go:171","msg":"trace[1196166477] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"1.493227279s","start":"2026-04-20T10:11:35.523851Z","end":"2026-04-20T10:11:37.017079Z","steps":["trace[1196166477] 'process raft request' (duration: 1.49197908s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:37.018384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:35.523835Z","time spent":"1.493918506s","remote":"127.0.0.1:41950","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-20T10:11:37.03155Z","caller":"traceutil/trace.go:171","msg":"trace[1598465583] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:879; }","duration":"1.480414602s","start":"2026-04-20T10:11:35.551108Z","end":"2026-04-20T10:11:37.031522Z","steps":["trace[1598465583] 'read index received' (duration: 1.464532456s)","trace[1598465583] 'applied index is now lower than readState.Index' (duration: 15.881026ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:11:37.031706Z","caller":"traceutil/trace.go:171","msg":"trace[2008323596] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"822.651195ms","start":"2026-04-20T10:11:36.20904Z","end":"2026-04-20T10:11:37.031692Z","steps":["trace[2008323596] 'process raft request' (duration: 822.416819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:37.03184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:36.209015Z","time spent":"822.733748ms","remote":"127.0.0.1:41928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:11:37.03156Z","caller":"traceutil/trace.go:171","msg":"trace[739097606] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"1.507083488s","start":"2026-04-20T10:11:35.524454Z","end":"2026-04-20T10:11:37.031537Z","steps":["trace[739097606] 'process raft request' (duration: 1.501143581s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:37.032166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.433685067s","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-20T10:11:37.032236Z","caller":"traceutil/trace.go:171","msg":"trace[573908582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:846; }","duration":"1.43375397s","start":"2026-04-20T10:11:35.598464Z","end":"2026-04-20T10:11:37.032218Z","steps":["trace[573908582] 'agreement among raft nodes before linearized reading' (duration: 1.433583755s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:37.032288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:35.598422Z","time spent":"1.433851212s","remote":"127.0.0.1:41950","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-20T10:11:37.032261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.837427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T10:11:37.032386Z","caller":"traceutil/trace.go:171","msg":"trace[1382899899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:846; }","duration":"846.025721ms","start":"2026-04-20T10:11:36.186345Z","end":"2026-04-20T10:11:37.03237Z","steps":["trace[1382899899] 'agreement among raft nodes before linearized reading' (duration: 845.757134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:37.032475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:36.186323Z","time spent":"846.141743ms","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T10:11:37.032551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020183782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:11:37.032753Z","caller":"traceutil/trace.go:171","msg":"trace[1514447523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:846; }","duration":"1.020404528s","start":"2026-04-20T10:11:36.012328Z","end":"2026-04-20T10:11:37.032732Z","steps":["trace[1514447523] 'agreement among raft nodes before linearized reading' (duration: 1.019970968s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:37.032646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.516783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:11:37.032832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:36.012317Z","time spent":"1.0205032s","remote":"127.0.0.1:41658","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-20T10:11:37.032932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481799473s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T10:11:37.032978Z","caller":"traceutil/trace.go:171","msg":"trace[636723126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:846; }","duration":"1.481898536s","start":"2026-04-20T10:11:35.551069Z","end":"2026-04-20T10:11:37.032968Z","steps":["trace[636723126] 'agreement among raft nodes before linearized reading' (duration: 1.480960514s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:37.03287Z","caller":"traceutil/trace.go:171","msg":"trace[1149430226] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:846; }","duration":"290.210279ms","start":"2026-04-20T10:11:36.742639Z","end":"2026-04-20T10:11:37.032849Z","steps":["trace[1149430226] 'agreement among raft nodes before linearized reading' (duration: 289.486133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:37.033024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:35.551051Z","time spent":"1.481966077s","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T10:11:37.033549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:35.524446Z","time spent":"1.507567968s","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:11:37.329553Z","caller":"traceutil/trace.go:171","msg":"trace[584430940] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"116.939968ms","start":"2026-04-20T10:11:37.212181Z","end":"2026-04-20T10:11:37.329121Z","steps":["trace[584430940] 'process raft request' (duration: 116.749953ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:59.357531Z","caller":"traceutil/trace.go:171","msg":"trace[1476361561] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1084; }","duration":"217.464237ms","start":"2026-04-20T10:11:59.140042Z","end":"2026-04-20T10:11:59.357506Z","steps":["trace[1476361561] 'read index received' (duration: 170.608174ms)","trace[1476361561] 'applied index is now lower than readState.Index' (duration: 46.855003ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:11:59.357715Z","caller":"traceutil/trace.go:171","msg":"trace[1136609265] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"220.113244ms","start":"2026-04-20T10:11:59.137517Z","end":"2026-04-20T10:11:59.35763Z","steps":["trace[1136609265] 'process raft request' (duration: 172.894472ms)","trace[1136609265] 'compare' (duration: 46.877592ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:11:59.357673Z","caller":"traceutil/trace.go:171","msg":"trace[157454823] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"219.276472ms","start":"2026-04-20T10:11:59.138366Z","end":"2026-04-20T10:11:59.357643Z","steps":["trace[157454823] 'process raft request' (duration: 219.088136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:59.357809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.746793ms","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-20T10:11:59.357856Z","caller":"traceutil/trace.go:171","msg":"trace[1936917411] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1046; }","duration":"217.837796ms","start":"2026-04-20T10:11:59.140005Z","end":"2026-04-20T10:11:59.357843Z","steps":["trace[1936917411] 'agreement among raft nodes before linearized reading' (duration: 217.677762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:59.365097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.795441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T10:11:59.365153Z","caller":"traceutil/trace.go:171","msg":"trace[1729412217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1047; }","duration":"224.889794ms","start":"2026-04-20T10:11:59.140249Z","end":"2026-04-20T10:11:59.365139Z","steps":["trace[1729412217] 'agreement among raft nodes before linearized reading' (duration: 224.7277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:07.546859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.813544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:12:07.547029Z","caller":"traceutil/trace.go:171","msg":"trace[378591457] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1114; }","duration":"104.941035ms","start":"2026-04-20T10:12:07.442073Z","end":"2026-04-20T10:12:07.547014Z","steps":["trace[378591457] 'read index received' (duration: 104.815292ms)","trace[378591457] 'applied index is now lower than readState.Index' (duration: 124.763µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:12:07.547326Z","caller":"traceutil/trace.go:171","msg":"trace[792978107] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"150.526524ms","start":"2026-04-20T10:12:07.39677Z","end":"2026-04-20T10:12:07.547297Z","steps":["trace[792978107] 'process raft request' (duration: 150.179134ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:07.547374Z","caller":"traceutil/trace.go:171","msg":"trace[676714829] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"150.684508ms","start":"2026-04-20T10:12:07.396661Z","end":"2026-04-20T10:12:07.547346Z","steps":["trace[676714829] 'process raft request' (duration: 26.286849ms)","trace[676714829] 'compare' (duration: 123.535467ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:12:07.54753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.438178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T10:12:07.547633Z","caller":"traceutil/trace.go:171","msg":"trace[2053221239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1074; }","duration":"105.578021ms","start":"2026-04-20T10:12:07.442036Z","end":"2026-04-20T10:12:07.547614Z","steps":["trace[2053221239] 'agreement among raft nodes before linearized reading' (duration: 105.294615ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:09.666913Z","caller":"traceutil/trace.go:171","msg":"trace[1711947924] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1120; }","duration":"105.547375ms","start":"2026-04-20T10:12:09.561347Z","end":"2026-04-20T10:12:09.666895Z","steps":["trace[1711947924] 'read index received' (duration: 77.044194ms)","trace[1711947924] 'applied index is now lower than readState.Index' (duration: 28.502491ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:12:09.667102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.735842ms","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-20T10:12:09.667058Z","caller":"traceutil/trace.go:171","msg":"trace[1471506966] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"112.293462ms","start":"2026-04-20T10:12:09.554699Z","end":"2026-04-20T10:12:09.666993Z","steps":["trace[1471506966] 'process raft request' (duration: 112.079486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:09.667307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.541499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T10:12:09.667388Z","caller":"traceutil/trace.go:171","msg":"trace[690904012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1080; }","duration":"102.632351ms","start":"2026-04-20T10:12:09.564737Z","end":"2026-04-20T10:12:09.667369Z","steps":["trace[690904012] 'agreement among raft nodes before linearized reading' (duration: 102.400575ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:09.667135Z","caller":"traceutil/trace.go:171","msg":"trace[871610531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1080; }","duration":"105.788463ms","start":"2026-04-20T10:12:09.561338Z","end":"2026-04-20T10:12:09.667126Z","steps":["trace[871610531] 'agreement among raft nodes before linearized reading' (duration: 105.661479ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:19.453312Z","caller":"traceutil/trace.go:171","msg":"trace[581134697] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"104.361916ms","start":"2026-04-20T10:12:19.348933Z","end":"2026-04-20T10:12:19.453295Z","steps":["trace[581134697] 'process raft request' (duration: 104.017766ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:33.649978Z","caller":"traceutil/trace.go:171","msg":"trace[424334369] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"126.710141ms","start":"2026-04-20T10:12:33.523244Z","end":"2026-04-20T10:12:33.649954Z","steps":["trace[424334369] 'process raft request' (duration: 126.539347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:26.274102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.940216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T10:13:26.274185Z","caller":"traceutil/trace.go:171","msg":"trace[305658856] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1370; }","duration":"140.08055ms","start":"2026-04-20T10:13:26.134088Z","end":"2026-04-20T10:13:26.274169Z","steps":["trace[305658856] 'range keys from in-memory index tree' (duration: 139.770451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:26.274609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.4544ms","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-20T10:13:26.274672Z","caller":"traceutil/trace.go:171","msg":"trace[1195766931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1370; }","duration":"155.585033ms","start":"2026-04-20T10:13:26.119072Z","end":"2026-04-20T10:13:26.274657Z","steps":["trace[1195766931] 'range keys from in-memory index tree' (duration: 155.250484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:32.583265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003660985383776,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T10:13:32.590551Z","caller":"traceutil/trace.go:171","msg":"trace[381171688] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1454; }","duration":"507.616529ms","start":"2026-04-20T10:13:32.082914Z","end":"2026-04-20T10:13:32.590531Z","steps":["trace[381171688] 'read index received' (duration: 507.454195ms)","trace[381171688] 'applied index is now lower than readState.Index' (duration: 161.674µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:13:32.590688Z","caller":"traceutil/trace.go:171","msg":"trace[1032205161] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"524.799004ms","start":"2026-04-20T10:13:32.065861Z","end":"2026-04-20T10:13:32.59066Z","steps":["trace[1032205161] 'process raft request' (duration: 524.563296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:32.590812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.880027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T10:13:32.590857Z","caller":"traceutil/trace.go:171","msg":"trace[1543868284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1394; }","duration":"507.966109ms","start":"2026-04-20T10:13:32.082881Z","end":"2026-04-20T10:13:32.590847Z","steps":["trace[1543868284] 'agreement among raft nodes before linearized reading' (duration: 507.800084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:32.590899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:13:32.065839Z","time spent":"524.946098ms","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:13:32.590984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:13:32.082865Z","time spent":"508.105593ms","remote":"127.0.0.1:41950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T10:13:32.591158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.766103ms","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-20T10:13:32.591237Z","caller":"traceutil/trace.go:171","msg":"trace[249233768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1394; }","duration":"507.879816ms","start":"2026-04-20T10:13:32.083336Z","end":"2026-04-20T10:13:32.591216Z","steps":["trace[249233768] 'agreement among raft nodes before linearized reading' (duration: 507.681781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:32.591295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:13:32.08332Z","time spent":"507.961908ms","remote":"127.0.0.1:41950","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-20T10:13:32.591308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.4239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T10:13:32.591404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.594628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:13:32.59143Z","caller":"traceutil/trace.go:171","msg":"trace[499844552] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1394; }","duration":"163.65169ms","start":"2026-04-20T10:13:32.427771Z","end":"2026-04-20T10:13:32.591423Z","steps":["trace[499844552] 'agreement among raft nodes before linearized reading' (duration: 163.619209ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:32.591409Z","caller":"traceutil/trace.go:171","msg":"trace[104664958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1394; }","duration":"282.573725ms","start":"2026-04-20T10:13:32.308823Z","end":"2026-04-20T10:13:32.591397Z","steps":["trace[104664958] 'agreement among raft nodes before linearized reading' (duration: 282.342178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:32.591353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.597411ms","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-20T10:13:32.591609Z","caller":"traceutil/trace.go:171","msg":"trace[689695677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1394; }","duration":"289.887249ms","start":"2026-04-20T10:13:32.30171Z","end":"2026-04-20T10:13:32.591597Z","steps":["trace[689695677] 'agreement among raft nodes before linearized reading' (duration: 289.58922ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:32.69768Z","caller":"traceutil/trace.go:171","msg":"trace[668996911] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"101.034719ms","start":"2026-04-20T10:13:32.596384Z","end":"2026-04-20T10:13:32.697419Z","steps":["trace[668996911] 'process raft request' (duration: 89.718717ms)","trace[668996911] 'compare' (duration: 10.89689ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:13:40.312683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.506195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/local-path-provisioner\" ","response":"range_response_count:1 size:1106"} {"level":"info","ts":"2026-04-20T10:13:40.312779Z","caller":"traceutil/trace.go:171","msg":"trace[1298080042] range","detail":"{range_begin:/registry/clusterroles/local-path-provisioner; range_end:; response_count:1; response_revision:1424; }","duration":"128.61856ms","start":"2026-04-20T10:13:40.18414Z","end":"2026-04-20T10:13:40.312759Z","steps":["trace[1298080042] 'range keys from in-memory index tree' (duration: 128.339911ms)"],"step_count":1}