{"level":"warn","ts":"2026-04-21T17:08:23.567072Z","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-21T17:08:23.56728Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-21T17:08:23.56739Z","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-21T17:08:23.567445Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-04-21T17:08:23.567483Z","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-21T17:08:23.568248Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-04-21T17:08:23.568413Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-21T17:08:23.573017Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.347643ms"} {"level":"info","ts":"2026-04-21T17:08:23.618345Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-04-21T17:08:23.618579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T17:08:23.618684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-04-21T17:08:23.618733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T17:08:23.618776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-04-21T17:08:23.618868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-04-21T17:08:23.626814Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T17:08:23.628325Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T17:08:23.629365Z","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-21T17:08:23.63766Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T17:08:23.638287Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T17:08:23.638424Z","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-21T17:08:23.638509Z","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-21T17:08:23.638534Z","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-21T17:08:23.646822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-04-21T17:08:23.64693Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-04-21T17:08:23.647552Z","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-21T17:08:23.647674Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-04-21T17:08:23.648127Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-04-21T17:08:23.647841Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T17:08:23.647883Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T17:08:24.619758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T17:08:24.619828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T17:08:24.619871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-04-21T17:08:24.61989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-04-21T17:08:24.619918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-04-21T17:08:24.619935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-04-21T17:08:24.619953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-04-21T17:08:24.623416Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:08:24.624791Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T17:08:24.624828Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T17:08:24.624862Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T17:08:24.625484Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T17:08:24.625525Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T17:08:24.627604Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:08:24.627785Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:08:24.627841Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:08:24.629104Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T17:08:24.629137Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-04-21T17:08:30.653114Z","caller":"traceutil/trace.go:171","msg":"trace[493294466] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:270; }","duration":"106.637461ms","start":"2026-04-21T17:08:30.546437Z","end":"2026-04-21T17:08:30.653075Z","steps":["trace[493294466] 'read index received' (duration: 19.854242ms)","trace[493294466] 'applied index is now lower than readState.Index' (duration: 86.782079ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:08:30.653264Z","caller":"traceutil/trace.go:171","msg":"trace[32336377] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"141.808299ms","start":"2026-04-21T17:08:30.51142Z","end":"2026-04-21T17:08:30.653229Z","steps":["trace[32336377] 'process raft request' (duration: 54.805765ms)","trace[32336377] 'compare' (duration: 86.649487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:08:30.653779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.052509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:08:30.653977Z","caller":"traceutil/trace.go:171","msg":"trace[667751142] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:263; }","duration":"107.57894ms","start":"2026-04-21T17:08:30.546371Z","end":"2026-04-21T17:08:30.65395Z","steps":["trace[667751142] 'agreement among raft nodes before linearized reading' (duration: 106.984867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:31.278074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.25689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:08:31.278231Z","caller":"traceutil/trace.go:171","msg":"trace[913213195] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:273; }","duration":"506.032927ms","start":"2026-04-21T17:08:30.772181Z","end":"2026-04-21T17:08:31.278214Z","steps":["trace[913213195] 'read index received' (duration: 1.132413ms)","trace[913213195] 'applied index is now lower than readState.Index' (duration: 504.899084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:08:31.278333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.148039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-04-21T17:08:31.278368Z","caller":"traceutil/trace.go:171","msg":"trace[1905856392] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:266; }","duration":"506.230532ms","start":"2026-04-21T17:08:30.772129Z","end":"2026-04-21T17:08:31.278359Z","steps":["trace[1905856392] 'agreement among raft nodes before linearized reading' (duration: 506.13126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:31.278403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:30.7721Z","time spent":"506.294462ms","remote":"127.0.0.1:52500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4002,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-04-21T17:08:31.278587Z","caller":"traceutil/trace.go:171","msg":"trace[1785388502] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"530.353842ms","start":"2026-04-21T17:08:30.748182Z","end":"2026-04-21T17:08:31.278536Z","steps":["trace[1785388502] 'process raft request' (duration: 25.09559ms)","trace[1785388502] 'compare' (duration: 504.104398ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:08:31.278785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:30.748146Z","time spent":"530.570155ms","remote":"127.0.0.1:52528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T17:08:31.363194Z","caller":"traceutil/trace.go:171","msg":"trace[1606042867] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"589.437312ms","start":"2026-04-21T17:08:30.773737Z","end":"2026-04-21T17:08:31.363174Z","steps":["trace[1606042867] 'process raft request' (duration: 589.33871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:31.363328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:30.77372Z","time spent":"589.552415ms","remote":"127.0.0.1:52500","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T17:08:31.502899Z","caller":"traceutil/trace.go:171","msg":"trace[1124608173] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:274; }","duration":"219.633534ms","start":"2026-04-21T17:08:31.283231Z","end":"2026-04-21T17:08:31.502865Z","steps":["trace[1124608173] 'read index received' (duration: 79.925703ms)","trace[1124608173] 'applied index is now lower than readState.Index' (duration: 139.706841ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:08:31.502976Z","caller":"traceutil/trace.go:171","msg":"trace[1920036807] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"203.874507ms","start":"2026-04-21T17:08:31.299072Z","end":"2026-04-21T17:08:31.502946Z","steps":["trace[1920036807] 'process raft request' (duration: 203.675784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:31.50312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.845528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:08:31.502883Z","caller":"traceutil/trace.go:171","msg":"trace[1351577695] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"728.305554ms","start":"2026-04-21T17:08:30.77453Z","end":"2026-04-21T17:08:31.502836Z","steps":["trace[1351577695] 'process raft request' (duration: 678.446822ms)","trace[1351577695] 'compare' (duration: 49.679189ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:08:31.503266Z","caller":"traceutil/trace.go:171","msg":"trace[1346427233] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:267; }","duration":"220.057761ms","start":"2026-04-21T17:08:31.283185Z","end":"2026-04-21T17:08:31.503243Z","steps":["trace[1346427233] 'agreement among raft nodes before linearized reading' (duration: 219.822566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:31.503871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:30.774492Z","time spent":"728.782694ms","remote":"127.0.0.1:52500","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T17:08:31.50311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.379612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-04-21T17:08:31.505892Z","caller":"traceutil/trace.go:171","msg":"trace[1651337371] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:267; }","duration":"141.217882ms","start":"2026-04-21T17:08:31.364657Z","end":"2026-04-21T17:08:31.505875Z","steps":["trace[1651337371] 'agreement among raft nodes before linearized reading' (duration: 138.372163ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:36.257812Z","caller":"traceutil/trace.go:171","msg":"trace[1813611754] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"115.103646ms","start":"2026-04-21T17:08:36.142634Z","end":"2026-04-21T17:08:36.257737Z","steps":["trace[1813611754] 'process raft request' (duration: 114.918073ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:36.264441Z","caller":"traceutil/trace.go:171","msg":"trace[1355388411] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"121.042058ms","start":"2026-04-21T17:08:36.143372Z","end":"2026-04-21T17:08:36.264414Z","steps":["trace[1355388411] 'process raft request' (duration: 120.759723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:36.92884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.412047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-qjzbd\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-04-21T17:08:36.929109Z","caller":"traceutil/trace.go:171","msg":"trace[1951074058] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-qjzbd; range_end:; response_count:1; response_revision:363; }","duration":"105.849487ms","start":"2026-04-21T17:08:36.823187Z","end":"2026-04-21T17:08:36.929036Z","steps":["trace[1951074058] 'range keys from in-memory index tree' (duration: 105.237724ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:37.125046Z","caller":"traceutil/trace.go:171","msg":"trace[639390618] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"117.854628ms","start":"2026-04-21T17:08:37.007164Z","end":"2026-04-21T17:08:37.125018Z","steps":["trace[639390618] 'process raft request' (duration: 70.611563ms)","trace[639390618] 'compare' (duration: 47.073552ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:08:37.125721Z","caller":"traceutil/trace.go:171","msg":"trace[1035252066] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"114.208994ms","start":"2026-04-21T17:08:37.011481Z","end":"2026-04-21T17:08:37.12569Z","steps":["trace[1035252066] 'process raft request' (duration: 114.04555ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:43.820459Z","caller":"traceutil/trace.go:171","msg":"trace[1701660883] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"191.150161ms","start":"2026-04-21T17:08:43.629283Z","end":"2026-04-21T17:08:43.820433Z","steps":["trace[1701660883] 'process raft request' (duration: 190.946137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:44.795458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.462713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:08:44.795662Z","caller":"traceutil/trace.go:171","msg":"trace[605124648] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"821.093512ms","start":"2026-04-21T17:08:43.974544Z","end":"2026-04-21T17:08:44.795637Z","steps":["trace[605124648] 'process raft request' (duration: 317.25117ms)","trace[605124648] 'compare' (duration: 503.331441ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:08:44.795725Z","caller":"traceutil/trace.go:171","msg":"trace[41659942] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"820.785054ms","start":"2026-04-21T17:08:43.974922Z","end":"2026-04-21T17:08:44.795707Z","steps":["trace[41659942] 'process raft request' (duration: 820.713763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:44.795792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:43.974885Z","time spent":"820.879637ms","remote":"127.0.0.1:52384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T17:08:44.795773Z","caller":"traceutil/trace.go:171","msg":"trace[796050294] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"821.133051ms","start":"2026-04-21T17:08:43.974604Z","end":"2026-04-21T17:08:44.795737Z","steps":["trace[796050294] 'process raft request' (duration: 820.967948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:44.795946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:43.974601Z","time spent":"821.257814ms","remote":"127.0.0.1:52822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:08:44.795952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:43.974538Z","time spent":"821.164852ms","remote":"127.0.0.1:52822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:08:44.79597Z","caller":"traceutil/trace.go:171","msg":"trace[1893123829] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:403; }","duration":"488.634604ms","start":"2026-04-21T17:08:44.307293Z","end":"2026-04-21T17:08:44.795928Z","steps":["trace[1893123829] 'read index received' (duration: 63.382µs)","trace[1893123829] 'applied index is now lower than readState.Index' (duration: 488.568062ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:08:44.796031Z","caller":"traceutil/trace.go:171","msg":"trace[1529217475] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"821.084321ms","start":"2026-04-21T17:08:43.974939Z","end":"2026-04-21T17:08:44.796023Z","steps":["trace[1529217475] 'process raft request' (duration: 820.738594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:44.79607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:43.974917Z","time spent":"821.136151ms","remote":"127.0.0.1:52384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T17:08:44.796171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.920025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T17:08:44.796156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.856648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T17:08:44.796207Z","caller":"traceutil/trace.go:171","msg":"trace[2109080023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:394; }","duration":"467.015329ms","start":"2026-04-21T17:08:44.329181Z","end":"2026-04-21T17:08:44.796197Z","steps":["trace[2109080023] 'agreement among raft nodes before linearized reading' (duration: 466.925767ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:44.796227Z","caller":"traceutil/trace.go:171","msg":"trace[465876252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:394; }","duration":"488.980122ms","start":"2026-04-21T17:08:44.30723Z","end":"2026-04-21T17:08:44.79621Z","steps":["trace[465876252] 'agreement among raft nodes before linearized reading' (duration: 488.847789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:44.796242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:44.329121Z","time spent":"467.11301ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:08:44.796257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:44.307207Z","time spent":"489.044382ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T17:08:44.796281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.863985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-21T17:08:44.796289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.00932ms","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-21T17:08:44.79633Z","caller":"traceutil/trace.go:171","msg":"trace[1019180554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:394; }","duration":"419.074242ms","start":"2026-04-21T17:08:44.377236Z","end":"2026-04-21T17:08:44.79631Z","steps":["trace[1019180554] 'agreement among raft nodes before linearized reading' (duration: 419.02453ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:44.796336Z","caller":"traceutil/trace.go:171","msg":"trace[1183863834] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:394; }","duration":"353.944039ms","start":"2026-04-21T17:08:44.442362Z","end":"2026-04-21T17:08:44.796306Z","steps":["trace[1183863834] 'agreement among raft nodes before linearized reading' (duration: 353.859797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:44.79635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:44.377173Z","time spent":"419.172723ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T17:08:44.796361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:44.442303Z","time spent":"354.053109ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T17:08:47.248925Z","caller":"traceutil/trace.go:171","msg":"trace[1993084609] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"396.794986ms","start":"2026-04-21T17:08:46.8521Z","end":"2026-04-21T17:08:47.248895Z","steps":["trace[1993084609] 'process raft request' (duration: 396.700595ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:47.249042Z","caller":"traceutil/trace.go:171","msg":"trace[524872666] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"397.077371ms","start":"2026-04-21T17:08:46.851864Z","end":"2026-04-21T17:08:47.248942Z","steps":["trace[524872666] 'process raft request' (duration: 396.761015ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:47.249251Z","caller":"traceutil/trace.go:171","msg":"trace[1729019798] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"396.840867ms","start":"2026-04-21T17:08:46.852393Z","end":"2026-04-21T17:08:47.249234Z","steps":["trace[1729019798] 'process raft request' (duration: 396.459439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:47.249319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:46.851839Z","time spent":"397.347897ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:08:47.249102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:46.852089Z","time spent":"396.916298ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:08:47.249539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:46.852381Z","time spent":"396.966419ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:08:48.778695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:48.148476Z","time spent":"630.208401ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-21T17:08:48.778806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.025923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:08:48.778681Z","caller":"traceutil/trace.go:171","msg":"trace[1866580986] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"448.87811ms","start":"2026-04-21T17:08:48.329753Z","end":"2026-04-21T17:08:48.778631Z","steps":["trace[1866580986] 'read index received' (duration: 448.583754ms)","trace[1866580986] 'applied index is now lower than readState.Index' (duration: 293.096µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:08:48.779159Z","caller":"traceutil/trace.go:171","msg":"trace[1435747249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"449.433892ms","start":"2026-04-21T17:08:48.329706Z","end":"2026-04-21T17:08:48.77914Z","steps":["trace[1435747249] 'agreement among raft nodes before linearized reading' (duration: 449.026964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:48.779294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:48.329685Z","time spent":"449.591044ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:08:50.826409Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225655340877504,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T17:08:51.108097Z","caller":"traceutil/trace.go:171","msg":"trace[1072159571] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"781.776502ms","start":"2026-04-21T17:08:50.326296Z","end":"2026-04-21T17:08:51.108072Z","steps":["trace[1072159571] 'read index received' (duration: 781.598278ms)","trace[1072159571] 'applied index is now lower than readState.Index' (duration: 177.004µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:08:51.10819Z","caller":"traceutil/trace.go:171","msg":"trace[375388820] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"802.053238ms","start":"2026-04-21T17:08:50.306105Z","end":"2026-04-21T17:08:51.108158Z","steps":["trace[375388820] 'process raft request' (duration: 801.788352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:51.108294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.000206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:08:51.108322Z","caller":"traceutil/trace.go:171","msg":"trace[1467809014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"782.080789ms","start":"2026-04-21T17:08:50.326233Z","end":"2026-04-21T17:08:51.108314Z","steps":["trace[1467809014] 'agreement among raft nodes before linearized reading' (duration: 782.024518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:51.108343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:50.326209Z","time spent":"782.12951ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:08:51.108364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:50.306082Z","time spent":"802.1833ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:08:51.108388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.51261ms","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-21T17:08:51.108447Z","caller":"traceutil/trace.go:171","msg":"trace[1367773175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:428; }","duration":"208.644411ms","start":"2026-04-21T17:08:50.899786Z","end":"2026-04-21T17:08:51.10843Z","steps":["trace[1367773175] 'agreement among raft nodes before linearized reading' (duration: 208.486998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:57.286001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.014793ms","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-21T17:08:57.286103Z","caller":"traceutil/trace.go:171","msg":"trace[579326646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:473; }","duration":"103.173797ms","start":"2026-04-21T17:08:57.18291Z","end":"2026-04-21T17:08:57.286084Z","steps":["trace[579326646] 'range keys from in-memory index tree' (duration: 102.894792ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:58.461421Z","caller":"traceutil/trace.go:171","msg":"trace[1438760201] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"189.884134ms","start":"2026-04-21T17:08:58.271517Z","end":"2026-04-21T17:08:58.461401Z","steps":["trace[1438760201] 'process raft request' (duration: 177.696974ms)","trace[1438760201] 'compare' (duration: 12.079228ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:08:58.462565Z","caller":"traceutil/trace.go:171","msg":"trace[384337723] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"190.877663ms","start":"2026-04-21T17:08:58.271666Z","end":"2026-04-21T17:08:58.462543Z","steps":["trace[384337723] 'process raft request' (duration: 189.831383ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:58.462758Z","caller":"traceutil/trace.go:171","msg":"trace[1686765732] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"187.112319ms","start":"2026-04-21T17:08:58.275639Z","end":"2026-04-21T17:08:58.462751Z","steps":["trace[1686765732] 'process raft request' (duration: 186.809364ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:58.462813Z","caller":"traceutil/trace.go:171","msg":"trace[1614838381] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"181.875007ms","start":"2026-04-21T17:08:58.28093Z","end":"2026-04-21T17:08:58.462805Z","steps":["trace[1614838381] 'process raft request' (duration: 181.633062ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:58.462903Z","caller":"traceutil/trace.go:171","msg":"trace[1991747931] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:530; }","duration":"134.192866ms","start":"2026-04-21T17:08:58.32869Z","end":"2026-04-21T17:08:58.462883Z","steps":["trace[1991747931] 'read index received' (duration: 120.592427ms)","trace[1991747931] 'applied index is now lower than readState.Index' (duration: 13.599739ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:08:58.462956Z","caller":"traceutil/trace.go:171","msg":"trace[486238529] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"157.040726ms","start":"2026-04-21T17:08:58.305908Z","end":"2026-04-21T17:08:58.462949Z","steps":["trace[486238529] 'process raft request' (duration: 156.802932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:58.462987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.282876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:08:58.462971Z","caller":"traceutil/trace.go:171","msg":"trace[1163793587] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"120.044346ms","start":"2026-04-21T17:08:58.342914Z","end":"2026-04-21T17:08:58.462959Z","steps":["trace[1163793587] 'process raft request' (duration: 119.902634ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:58.463006Z","caller":"traceutil/trace.go:171","msg":"trace[1187847236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"134.35098ms","start":"2026-04-21T17:08:58.32865Z","end":"2026-04-21T17:08:58.463001Z","steps":["trace[1187847236] 'agreement among raft nodes before linearized reading' (duration: 134.299928ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:58.779533Z","caller":"traceutil/trace.go:171","msg":"trace[1615550172] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"251.873006ms","start":"2026-04-21T17:08:58.527633Z","end":"2026-04-21T17:08:58.779505Z","steps":["trace[1615550172] 'process raft request' (duration: 238.448641ms)","trace[1615550172] 'compare' (duration: 13.264311ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:08:58.780572Z","caller":"traceutil/trace.go:171","msg":"trace[1926705309] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"225.876363ms","start":"2026-04-21T17:08:58.554651Z","end":"2026-04-21T17:08:58.780528Z","steps":["trace[1926705309] 'process raft request' (duration: 225.272541ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:58.780909Z","caller":"traceutil/trace.go:171","msg":"trace[300887596] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"209.019221ms","start":"2026-04-21T17:08:58.571851Z","end":"2026-04-21T17:08:58.78087Z","steps":["trace[300887596] 'process raft request' (duration: 208.321968ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:58.781049Z","caller":"traceutil/trace.go:171","msg":"trace[1268295458] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"224.762142ms","start":"2026-04-21T17:08:58.556269Z","end":"2026-04-21T17:08:58.781031Z","steps":["trace[1268295458] 'process raft request' (duration: 223.781553ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:59.639606Z","caller":"traceutil/trace.go:171","msg":"trace[307499727] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:543; }","duration":"332.338112ms","start":"2026-04-21T17:08:59.30719Z","end":"2026-04-21T17:08:59.639528Z","steps":["trace[307499727] 'read index received' (duration: 259.790295ms)","trace[307499727] 'applied index is now lower than readState.Index' (duration: 72.546357ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:08:59.639835Z","caller":"traceutil/trace.go:171","msg":"trace[419650530] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"833.700261ms","start":"2026-04-21T17:08:58.806089Z","end":"2026-04-21T17:08:59.639789Z","steps":["trace[419650530] 'process raft request' (duration: 833.13045ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:08:59.639993Z","caller":"traceutil/trace.go:171","msg":"trace[1558966076] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"778.321818ms","start":"2026-04-21T17:08:58.861475Z","end":"2026-04-21T17:08:59.639797Z","steps":["trace[1558966076] 'process raft request' (duration: 777.960071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:59.640241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:58.806068Z","time spent":"834.013657ms","remote":"127.0.0.1:52350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:08:59.64041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:58.861445Z","time spent":"778.772498ms","remote":"127.0.0.1:52350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T17:08:59.63982Z","caller":"traceutil/trace.go:171","msg":"trace[445753686] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"842.265919ms","start":"2026-04-21T17:08:58.797519Z","end":"2026-04-21T17:08:59.639785Z","steps":["trace[445753686] 'process raft request' (duration: 769.464586ms)","trace[445753686] 'compare' (duration: 71.884225ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:08:59.640701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.488705ms","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-21T17:08:59.640795Z","caller":"traceutil/trace.go:171","msg":"trace[2122442144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"333.658638ms","start":"2026-04-21T17:08:59.307121Z","end":"2026-04-21T17:08:59.640779Z","steps":["trace[2122442144] 'agreement among raft nodes before linearized reading' (duration: 332.578377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:59.640852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:59.307097Z","time spent":"333.73119ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T17:08:59.64085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:08:58.797498Z","time spent":"843.198477ms","remote":"127.0.0.1:52350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:08:59.640961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.501247ms","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-21T17:08:59.641004Z","caller":"traceutil/trace.go:171","msg":"trace[2146361435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"295.57866ms","start":"2026-04-21T17:08:59.345408Z","end":"2026-04-21T17:08:59.640986Z","steps":["trace[2146361435] 'agreement among raft nodes before linearized reading' (duration: 294.423867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:59.641086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.725277ms","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-21T17:08:59.641236Z","caller":"traceutil/trace.go:171","msg":"trace[1208827014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"277.889592ms","start":"2026-04-21T17:08:59.36329Z","end":"2026-04-21T17:08:59.64118Z","steps":["trace[1208827014] 'agreement among raft nodes before linearized reading' (duration: 276.531235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:08:59.641246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.728485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T17:08:59.641298Z","caller":"traceutil/trace.go:171","msg":"trace[451898057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"283.831889ms","start":"2026-04-21T17:08:59.357451Z","end":"2026-04-21T17:08:59.641283Z","steps":["trace[451898057] 'agreement among raft nodes before linearized reading' (duration: 282.499442ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:00.177225Z","caller":"traceutil/trace.go:171","msg":"trace[626958513] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"219.960357ms","start":"2026-04-21T17:08:59.957231Z","end":"2026-04-21T17:09:00.177192Z","steps":["trace[626958513] 'process raft request' (duration: 176.224417ms)","trace[626958513] 'compare' (duration: 43.570646ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:00.17897Z","caller":"traceutil/trace.go:171","msg":"trace[1713485455] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"213.989459ms","start":"2026-04-21T17:08:59.964954Z","end":"2026-04-21T17:09:00.178943Z","steps":["trace[1713485455] 'process raft request' (duration: 213.798305ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:00.319068Z","caller":"traceutil/trace.go:171","msg":"trace[1284090842] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"111.110435ms","start":"2026-04-21T17:09:00.207938Z","end":"2026-04-21T17:09:00.319049Z","steps":["trace[1284090842] 'process raft request' (duration: 111.048035ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:00.31948Z","caller":"traceutil/trace.go:171","msg":"trace[2080125434] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"113.191516ms","start":"2026-04-21T17:09:00.20628Z","end":"2026-04-21T17:09:00.319471Z","steps":["trace[2080125434] 'process raft request' (duration: 92.477089ms)","trace[2080125434] 'compare' (duration: 20.093394ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:00.669742Z","caller":"traceutil/trace.go:171","msg":"trace[474477662] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"171.371109ms","start":"2026-04-21T17:09:00.498325Z","end":"2026-04-21T17:09:00.669696Z","steps":["trace[474477662] 'process raft request' (duration: 169.166746ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:00.760244Z","caller":"traceutil/trace.go:171","msg":"trace[634231103] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"243.713681ms","start":"2026-04-21T17:09:00.516507Z","end":"2026-04-21T17:09:00.760221Z","steps":["trace[634231103] 'process raft request' (duration: 243.253501ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:00.760666Z","caller":"traceutil/trace.go:171","msg":"trace[2094091882] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"211.646892ms","start":"2026-04-21T17:09:00.549008Z","end":"2026-04-21T17:09:00.760655Z","steps":["trace[2094091882] 'process raft request' (duration: 211.148381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:00.914002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.408599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-21T17:09:00.914166Z","caller":"traceutil/trace.go:171","msg":"trace[502280887] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:554; }","duration":"114.621743ms","start":"2026-04-21T17:09:00.799515Z","end":"2026-04-21T17:09:00.914136Z","steps":["trace[502280887] 'range keys from in-memory index tree' (duration: 114.180064ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:01.327345Z","caller":"traceutil/trace.go:171","msg":"trace[1349740854] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"336.616199ms","start":"2026-04-21T17:09:00.990699Z","end":"2026-04-21T17:09:01.327316Z","steps":["trace[1349740854] 'read index received' (duration: 303.05072ms)","trace[1349740854] 'applied index is now lower than readState.Index' (duration: 33.564399ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:01.327612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.887384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-21T17:09:01.327657Z","caller":"traceutil/trace.go:171","msg":"trace[1514255397] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:556; }","duration":"336.985527ms","start":"2026-04-21T17:09:00.990661Z","end":"2026-04-21T17:09:01.327646Z","steps":["trace[1514255397] 'agreement among raft nodes before linearized reading' (duration: 336.758901ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:01.327632Z","caller":"traceutil/trace.go:171","msg":"trace[576349729] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"359.339065ms","start":"2026-04-21T17:09:00.968249Z","end":"2026-04-21T17:09:01.327588Z","steps":["trace[576349729] 'process raft request' (duration: 325.321477ms)","trace[576349729] 'compare' (duration: 33.58003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:01.327699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:00.990641Z","time spent":"337.049117ms","remote":"127.0.0.1:52350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151258,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-21T17:09:01.327828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:00.968225Z","time spent":"359.502588ms","remote":"127.0.0.1:52350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:09:01.333451Z","caller":"traceutil/trace.go:171","msg":"trace[1256708718] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"336.181669ms","start":"2026-04-21T17:09:00.996982Z","end":"2026-04-21T17:09:01.333164Z","steps":["trace[1256708718] 'process raft request' (duration: 335.586297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:01.33383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:00.996945Z","time spent":"336.675009ms","remote":"127.0.0.1:52350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:09:01.575551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.581629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:09:01.575668Z","caller":"traceutil/trace.go:171","msg":"trace[2048416416] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"189.258335ms","start":"2026-04-21T17:09:01.386389Z","end":"2026-04-21T17:09:01.575648Z","steps":["trace[2048416416] 'read index received' (duration: 70.519294ms)","trace[2048416416] 'applied index is now lower than readState.Index' (duration: 118.737701ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:01.575767Z","caller":"traceutil/trace.go:171","msg":"trace[1963664291] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"191.797915ms","start":"2026-04-21T17:09:01.383937Z","end":"2026-04-21T17:09:01.575735Z","steps":["trace[1963664291] 'process raft request' (duration: 72.928442ms)","trace[1963664291] 'compare' (duration: 118.473236ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:01.575856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.458659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31145"} {"level":"info","ts":"2026-04-21T17:09:01.575895Z","caller":"traceutil/trace.go:171","msg":"trace[1950992259] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:559; }","duration":"189.511721ms","start":"2026-04-21T17:09:01.386373Z","end":"2026-04-21T17:09:01.575885Z","steps":["trace[1950992259] 'agreement among raft nodes before linearized reading' (duration: 189.359157ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:01.828622Z","caller":"traceutil/trace.go:171","msg":"trace[961838262] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"104.171765ms","start":"2026-04-21T17:09:01.724428Z","end":"2026-04-21T17:09:01.8286Z","steps":["trace[961838262] 'process raft request' (duration: 100.649406ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:01.830327Z","caller":"traceutil/trace.go:171","msg":"trace[1122703013] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"105.112113ms","start":"2026-04-21T17:09:01.725183Z","end":"2026-04-21T17:09:01.830295Z","steps":["trace[1122703013] 'process raft request' (duration: 104.970391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:05.079008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.766328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:09:05.079406Z","caller":"traceutil/trace.go:171","msg":"trace[517609109] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"226.326123ms","start":"2026-04-21T17:09:04.853049Z","end":"2026-04-21T17:09:05.079375Z","steps":["trace[517609109] 'process raft request' (duration: 101.067215ms)","trace[517609109] 'compare' (duration: 124.413271ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:05.07985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.045738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:05.079936Z","caller":"traceutil/trace.go:171","msg":"trace[725654324] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:576; }","duration":"225.18097ms","start":"2026-04-21T17:09:04.854735Z","end":"2026-04-21T17:09:05.079916Z","steps":["trace[725654324] 'agreement among raft nodes before linearized reading' (duration: 224.891994ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:05.079945Z","caller":"traceutil/trace.go:171","msg":"trace[1060102070] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"224.545478ms","start":"2026-04-21T17:09:04.854775Z","end":"2026-04-21T17:09:05.079321Z","steps":["trace[1060102070] 'read index received' (duration: 99.356413ms)","trace[1060102070] 'applied index is now lower than readState.Index' (duration: 125.187145ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:05.080336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.417522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:05.080391Z","caller":"traceutil/trace.go:171","msg":"trace[1129204769] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:576; }","duration":"118.517125ms","start":"2026-04-21T17:09:04.961859Z","end":"2026-04-21T17:09:05.080376Z","steps":["trace[1129204769] 'agreement among raft nodes before linearized reading' (duration: 118.320141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:05.080626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.483334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:05.080668Z","caller":"traceutil/trace.go:171","msg":"trace[1074031502] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:576; }","duration":"118.530604ms","start":"2026-04-21T17:09:04.962128Z","end":"2026-04-21T17:09:05.080659Z","steps":["trace[1074031502] 'agreement among raft nodes before linearized reading' (duration: 118.465063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:05.080864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.960423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:05.080907Z","caller":"traceutil/trace.go:171","msg":"trace[1522557607] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:576; }","duration":"119.037995ms","start":"2026-04-21T17:09:04.961859Z","end":"2026-04-21T17:09:05.080897Z","steps":["trace[1522557607] 'agreement among raft nodes before linearized reading' (duration: 118.968164ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:07.8014Z","caller":"traceutil/trace.go:171","msg":"trace[1395028616] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.11750148s","start":"2026-04-21T17:09:06.68387Z","end":"2026-04-21T17:09:07.801371Z","steps":["trace[1395028616] 'process raft request' (duration: 1.116149424s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:07.801573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:06.683843Z","time spent":"1.117646994s","remote":"127.0.0.1:52410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036269,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:09:07.849488Z","caller":"traceutil/trace.go:171","msg":"trace[1923391198] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:609; }","duration":"520.929074ms","start":"2026-04-21T17:09:07.328523Z","end":"2026-04-21T17:09:07.849452Z","steps":["trace[1923391198] 'read index received' (duration: 471.254217ms)","trace[1923391198] 'applied index is now lower than readState.Index' (duration: 49.673727ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:07.849603Z","caller":"traceutil/trace.go:171","msg":"trace[985022784] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"670.457637ms","start":"2026-04-21T17:09:07.179105Z","end":"2026-04-21T17:09:07.849563Z","steps":["trace[985022784] 'process raft request' (duration: 670.176192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:07.849788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:07.179079Z","time spent":"670.625211ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:09:07.849832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.263351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T17:09:07.849905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.31995ms","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-21T17:09:07.849979Z","caller":"traceutil/trace.go:171","msg":"trace[2014230143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"107.444383ms","start":"2026-04-21T17:09:07.742516Z","end":"2026-04-21T17:09:07.849961Z","steps":["trace[2014230143] 'agreement among raft nodes before linearized reading' (duration: 107.31366ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:07.849916Z","caller":"traceutil/trace.go:171","msg":"trace[1540926298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"521.424554ms","start":"2026-04-21T17:09:07.328475Z","end":"2026-04-21T17:09:07.849899Z","steps":["trace[1540926298] 'agreement among raft nodes before linearized reading' (duration: 521.223731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:07.850106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:07.328459Z","time spent":"521.626988ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:09:08.995353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.510309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:09:08.995729Z","caller":"traceutil/trace.go:171","msg":"trace[1231383953] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.134029791s","start":"2026-04-21T17:09:07.861675Z","end":"2026-04-21T17:09:08.995705Z","steps":["trace[1231383953] 'process raft request' (duration: 1.133969129s)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:08.995773Z","caller":"traceutil/trace.go:171","msg":"trace[421532134] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"1.141882024s","start":"2026-04-21T17:09:07.853869Z","end":"2026-04-21T17:09:08.995751Z","steps":["trace[421532134] 'read index received' (duration: 354.853176ms)","trace[421532134] 'applied index is now lower than readState.Index' (duration: 787.026628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:08.99584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:07.861631Z","time spent":"1.134149065s","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:09:08.995972Z","caller":"traceutil/trace.go:171","msg":"trace[179642416] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.142451185s","start":"2026-04-21T17:09:07.853427Z","end":"2026-04-21T17:09:08.995878Z","steps":["trace[179642416] 'process raft request' (duration: 355.322005ms)","trace[179642416] 'compare' (duration: 786.08467ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:08.996063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1421868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:08.996004Z","caller":"traceutil/trace.go:171","msg":"trace[563008632] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.140184121s","start":"2026-04-21T17:09:07.855691Z","end":"2026-04-21T17:09:08.995875Z","steps":["trace[563008632] 'process raft request' (duration: 1.139813464s)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:08.996114Z","caller":"traceutil/trace.go:171","msg":"trace[1082008025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"1.142272362s","start":"2026-04-21T17:09:07.853827Z","end":"2026-04-21T17:09:08.9961Z","steps":["trace[1082008025] 'agreement among raft nodes before linearized reading' (duration: 1.1421946s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:08.996158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:07.853813Z","time spent":"1.142334172s","remote":"127.0.0.1:52294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:09:08.996199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114566322s","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-21T17:09:08.996278Z","caller":"traceutil/trace.go:171","msg":"trace[1717228157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"1.114651514s","start":"2026-04-21T17:09:07.881606Z","end":"2026-04-21T17:09:08.996257Z","steps":["trace[1717228157] 'agreement among raft nodes before linearized reading' (duration: 1.114423369s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:08.99633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:07.88152Z","time spent":"1.114797547s","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T17:09:08.996339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:07.855439Z","time spent":"1.140706561s","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:09:08.996411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.132966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-21T17:09:08.99642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100498417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T17:09:08.996486Z","caller":"traceutil/trace.go:171","msg":"trace[566019540] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:589; }","duration":"848.236549ms","start":"2026-04-21T17:09:08.148226Z","end":"2026-04-21T17:09:08.996462Z","steps":["trace[566019540] 'agreement among raft nodes before linearized reading' (duration: 847.962473ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:08.996496Z","caller":"traceutil/trace.go:171","msg":"trace[1075653873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"1.100609341s","start":"2026-04-21T17:09:07.895865Z","end":"2026-04-21T17:09:08.996474Z","steps":["trace[1075653873] 'agreement among raft nodes before linearized reading' (duration: 1.100434477s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:08.996535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:07.895847Z","time spent":"1.100679442s","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T17:09:08.996533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:08.148206Z","time spent":"848.31822ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"warn","ts":"2026-04-21T17:09:08.998033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:07.853402Z","time spent":"1.142609008s","remote":"127.0.0.1:52680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T17:09:09.915967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.973565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:09:09.916175Z","caller":"traceutil/trace.go:171","msg":"trace[1719999586] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"839.871796ms","start":"2026-04-21T17:09:09.07627Z","end":"2026-04-21T17:09:09.916142Z","steps":["trace[1719999586] 'read index received' (duration: 185.532555ms)","trace[1719999586] 'applied index is now lower than readState.Index' (duration: 654.337261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:09.916498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.522898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:09.916536Z","caller":"traceutil/trace.go:171","msg":"trace[1743324671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"909.56859ms","start":"2026-04-21T17:09:09.006956Z","end":"2026-04-21T17:09:09.916525Z","steps":["trace[1743324671] 'agreement among raft nodes before linearized reading' (duration: 909.449138ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:09.916492Z","caller":"traceutil/trace.go:171","msg":"trace[348813186] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"904.809576ms","start":"2026-04-21T17:09:09.011629Z","end":"2026-04-21T17:09:09.916439Z","steps":["trace[348813186] 'process raft request' (duration: 250.23114ms)","trace[348813186] 'compare' (duration: 653.774001ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:09.916576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:09.006951Z","time spent":"909.616341ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:09:09.916745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:09.011622Z","time spent":"904.98561ms","remote":"127.0.0.1:52338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:09:10.418016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225655340877862,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T17:09:10.918374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225655340877862,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T17:09:10.918498Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.436447582s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T17:09:10.919898Z","caller":"traceutil/trace.go:171","msg":"trace[514688300] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"1.002091865s","start":"2026-04-21T17:09:09.917765Z","end":"2026-04-21T17:09:10.919856Z","steps":["trace[514688300] 'read index received' (duration: 1.000894352s)","trace[514688300] 'applied index is now lower than readState.Index' (duration: 1.195833ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:10.92019Z","caller":"traceutil/trace.go:171","msg":"trace[27119580] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.43894145s","start":"2026-04-21T17:09:09.481198Z","end":"2026-04-21T17:09:10.920139Z","steps":["trace[27119580] 'process raft request' (duration: 1.437986541s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:10.920243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002442241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:10.920344Z","caller":"traceutil/trace.go:171","msg":"trace[811561196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"1.002567514s","start":"2026-04-21T17:09:09.917723Z","end":"2026-04-21T17:09:10.920291Z","steps":["trace[811561196] 'agreement among raft nodes before linearized reading' (duration: 1.002404451s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:10.920334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001153487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-04-21T17:09:10.920392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:09.917698Z","time spent":"1.002682156s","remote":"127.0.0.1:52294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T17:09:10.920419Z","caller":"traceutil/trace.go:171","msg":"trace[989670145] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:593; }","duration":"1.001287189s","start":"2026-04-21T17:09:09.919106Z","end":"2026-04-21T17:09:10.920393Z","steps":["trace[989670145] 'agreement among raft nodes before linearized reading' (duration: 1.001105126s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:10.920427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:09.481178Z","time spent":"1.439122774s","remote":"127.0.0.1:52410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:09:10.920484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:09.919086Z","time spent":"1.00138383s","remote":"127.0.0.1:52486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-04-21T17:09:12.019613Z","caller":"traceutil/trace.go:171","msg":"trace[1801558955] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"535.631409ms","start":"2026-04-21T17:09:11.483957Z","end":"2026-04-21T17:09:12.019588Z","steps":["trace[1801558955] 'process raft request' (duration: 534.319884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:12.019811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:11.483911Z","time spent":"535.781943ms","remote":"127.0.0.1:52410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036325,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:09:12.066847Z","caller":"traceutil/trace.go:171","msg":"trace[64280092] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"181.834733ms","start":"2026-04-21T17:09:11.884952Z","end":"2026-04-21T17:09:12.066787Z","steps":["trace[64280092] 'read index received' (duration: 132.860795ms)","trace[64280092] 'applied index is now lower than readState.Index' (duration: 48.973028ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:12.066875Z","caller":"traceutil/trace.go:171","msg":"trace[1520698372] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"387.038799ms","start":"2026-04-21T17:09:11.679776Z","end":"2026-04-21T17:09:12.066814Z","steps":["trace[1520698372] 'process raft request' (duration: 386.751603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:12.067109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:11.679748Z","time spent":"387.246223ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:09:12.067235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.24477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-djc5r\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-21T17:09:12.067329Z","caller":"traceutil/trace.go:171","msg":"trace[1349451051] range","detail":"{range_begin:/registry/pods/kube-system/cilium-djc5r; range_end:; response_count:1; response_revision:603; }","duration":"182.399892ms","start":"2026-04-21T17:09:11.884907Z","end":"2026-04-21T17:09:12.067307Z","steps":["trace[1349451051] 'agreement among raft nodes before linearized reading' (duration: 182.041106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:12.829351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225655340877886,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T17:09:13.330444Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225655340877886,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T17:09:13.515385Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.431933375s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T17:09:13.516197Z","caller":"traceutil/trace.go:171","msg":"trace[1760763477] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.432951734s","start":"2026-04-21T17:09:12.083217Z","end":"2026-04-21T17:09:13.516169Z","steps":["trace[1760763477] 'process raft request' (duration: 1.432396364s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:13.51632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:12.083175Z","time spent":"1.433068827s","remote":"127.0.0.1:52500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:09:13.666716Z","caller":"traceutil/trace.go:171","msg":"trace[183226440] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.574249376s","start":"2026-04-21T17:09:12.092427Z","end":"2026-04-21T17:09:13.666676Z","steps":["trace[183226440] 'process raft request' (duration: 1.53362987s)","trace[183226440] 'compare' (duration: 40.152756ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:13.666902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:12.092389Z","time spent":"1.574424969s","remote":"127.0.0.1:52570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T17:09:13.687048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358314456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:13.687111Z","caller":"traceutil/trace.go:171","msg":"trace[693135016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"1.358449268s","start":"2026-04-21T17:09:12.328651Z","end":"2026-04-21T17:09:13.687101Z","steps":["trace[693135016] 'agreement among raft nodes before linearized reading' (duration: 1.358308976s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:13.687152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:12.328627Z","time spent":"1.35851708s","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T17:09:13.686919Z","caller":"traceutil/trace.go:171","msg":"trace[600155957] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:629; }","duration":"1.358129743s","start":"2026-04-21T17:09:12.328708Z","end":"2026-04-21T17:09:13.686838Z","steps":["trace[600155957] 'read index received' (duration: 1.187396972s)","trace[600155957] 'applied index is now lower than readState.Index' (duration: 170.731041ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:13.687642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.547738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-21T17:09:13.687675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.777492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-21T17:09:13.687669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.625939ms","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-21T17:09:13.687695Z","caller":"traceutil/trace.go:171","msg":"trace[1492425208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"587.849925ms","start":"2026-04-21T17:09:13.099839Z","end":"2026-04-21T17:09:13.687689Z","steps":["trace[1492425208] 'agreement among raft nodes before linearized reading' (duration: 587.743443ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:13.687693Z","caller":"traceutil/trace.go:171","msg":"trace[555289783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"588.64699ms","start":"2026-04-21T17:09:13.099034Z","end":"2026-04-21T17:09:13.687681Z","steps":["trace[555289783] 'agreement among raft nodes before linearized reading' (duration: 588.557198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:13.687712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:13.09981Z","time spent":"587.897096ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T17:09:13.687718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:13.09901Z","time spent":"588.702492ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T17:09:13.687714Z","caller":"traceutil/trace.go:171","msg":"trace[1625618102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"634.726551ms","start":"2026-04-21T17:09:13.052974Z","end":"2026-04-21T17:09:13.6877Z","steps":["trace[1625618102] 'agreement among raft nodes before linearized reading' (duration: 634.64183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:13.687747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:13.052935Z","time spent":"634.804682ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T17:09:13.687882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.083178ms","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-21T17:09:13.687904Z","caller":"traceutil/trace.go:171","msg":"trace[1992329432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"635.153249ms","start":"2026-04-21T17:09:13.052744Z","end":"2026-04-21T17:09:13.687897Z","steps":["trace[1992329432] 'agreement among raft nodes before linearized reading' (duration: 634.802182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:13.687918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:13.052717Z","time spent":"635.1974ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T17:09:14.822192Z","caller":"traceutil/trace.go:171","msg":"trace[1375855709] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"124.042286ms","start":"2026-04-21T17:09:14.698114Z","end":"2026-04-21T17:09:14.822157Z","steps":["trace[1375855709] 'read index received' (duration: 123.808102ms)","trace[1375855709] 'applied index is now lower than readState.Index' (duration: 233.184µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:14.822246Z","caller":"traceutil/trace.go:171","msg":"trace[1482485361] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"164.755541ms","start":"2026-04-21T17:09:14.657446Z","end":"2026-04-21T17:09:14.822201Z","steps":["trace[1482485361] 'process raft request' (duration: 164.546117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:14.822473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.28467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:14.822559Z","caller":"traceutil/trace.go:171","msg":"trace[2000302831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"124.464983ms","start":"2026-04-21T17:09:14.698073Z","end":"2026-04-21T17:09:14.822538Z","steps":["trace[2000302831] 'agreement among raft nodes before linearized reading' (duration: 124.221488ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:16.021489Z","caller":"traceutil/trace.go:171","msg":"trace[1498512267] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"101.703721ms","start":"2026-04-21T17:09:15.919755Z","end":"2026-04-21T17:09:16.021458Z","steps":["trace[1498512267] 'process raft request' (duration: 101.553389ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:16.46238Z","caller":"traceutil/trace.go:171","msg":"trace[1292324805] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"174.519173ms","start":"2026-04-21T17:09:16.287837Z","end":"2026-04-21T17:09:16.462356Z","steps":["trace[1292324805] 'process raft request' (duration: 174.377011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:16.46274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.586784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:16.462841Z","caller":"traceutil/trace.go:171","msg":"trace[55534025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"133.737447ms","start":"2026-04-21T17:09:16.329081Z","end":"2026-04-21T17:09:16.462818Z","steps":["trace[55534025] 'agreement among raft nodes before linearized reading' (duration: 133.470772ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:16.463119Z","caller":"traceutil/trace.go:171","msg":"trace[1259425012] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:657; }","duration":"133.927361ms","start":"2026-04-21T17:09:16.329131Z","end":"2026-04-21T17:09:16.463058Z","steps":["trace[1259425012] 'read index received' (duration: 133.37628ms)","trace[1259425012] 'applied index is now lower than readState.Index' (duration: 547.91µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:17.357199Z","caller":"traceutil/trace.go:171","msg":"trace[1446950275] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"214.012148ms","start":"2026-04-21T17:09:17.143161Z","end":"2026-04-21T17:09:17.357173Z","steps":["trace[1446950275] 'process raft request' (duration: 144.363307ms)","trace[1446950275] 'compare' (duration: 69.487497ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:17.368512Z","caller":"traceutil/trace.go:171","msg":"trace[1197329517] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"162.136171ms","start":"2026-04-21T17:09:17.206337Z","end":"2026-04-21T17:09:17.368473Z","steps":["trace[1197329517] 'read index received' (duration: 81.332556ms)","trace[1197329517] 'applied index is now lower than readState.Index' (duration: 80.802075ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:17.368656Z","caller":"traceutil/trace.go:171","msg":"trace[248345971] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"216.070848ms","start":"2026-04-21T17:09:17.152565Z","end":"2026-04-21T17:09:17.368636Z","steps":["trace[248345971] 'process raft request' (duration: 215.707601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:17.36873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.329405ms","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-21T17:09:17.368805Z","caller":"traceutil/trace.go:171","msg":"trace[524787693] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:634; }","duration":"162.495527ms","start":"2026-04-21T17:09:17.206295Z","end":"2026-04-21T17:09:17.368791Z","steps":["trace[524787693] 'agreement among raft nodes before linearized reading' (duration: 162.285733ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:17.658071Z","caller":"traceutil/trace.go:171","msg":"trace[849229623] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"112.018007ms","start":"2026-04-21T17:09:17.546025Z","end":"2026-04-21T17:09:17.658043Z","steps":["trace[849229623] 'process raft request' (duration: 111.836233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:17.926236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.646225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:09:17.926377Z","caller":"traceutil/trace.go:171","msg":"trace[442893177] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"257.684079ms","start":"2026-04-21T17:09:17.668673Z","end":"2026-04-21T17:09:17.926358Z","steps":["trace[442893177] 'process raft request' (duration: 101.84894ms)","trace[442893177] 'compare' (duration: 155.485933ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:18.019284Z","caller":"traceutil/trace.go:171","msg":"trace[1815265183] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"288.107565ms","start":"2026-04-21T17:09:17.731148Z","end":"2026-04-21T17:09:18.019255Z","steps":["trace[1815265183] 'process raft request' (duration: 287.922821ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:18.019284Z","caller":"traceutil/trace.go:171","msg":"trace[1745763087] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:676; }","duration":"232.866082ms","start":"2026-04-21T17:09:17.78639Z","end":"2026-04-21T17:09:18.019256Z","steps":["trace[1745763087] 'read index received' (duration: 232.852882ms)","trace[1745763087] 'applied index is now lower than readState.Index' (duration: 11.18µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:18.019638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.220049ms","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-21T17:09:18.019797Z","caller":"traceutil/trace.go:171","msg":"trace[1534342722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"233.437662ms","start":"2026-04-21T17:09:17.786345Z","end":"2026-04-21T17:09:18.019783Z","steps":["trace[1534342722] 'agreement among raft nodes before linearized reading' (duration: 232.996504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:18.047392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.107607ms","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":"info","ts":"2026-04-21T17:09:18.047472Z","caller":"traceutil/trace.go:171","msg":"trace[1605316839] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:650; }","duration":"217.221269ms","start":"2026-04-21T17:09:17.830233Z","end":"2026-04-21T17:09:18.047455Z","steps":["trace[1605316839] 'agreement among raft nodes before linearized reading' (duration: 217.052065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:18.047421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.878752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-21T17:09:18.047565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.348082ms","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-21T17:09:18.047641Z","caller":"traceutil/trace.go:171","msg":"trace[1704747546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"231.112697ms","start":"2026-04-21T17:09:17.816481Z","end":"2026-04-21T17:09:18.047594Z","steps":["trace[1704747546] 'agreement among raft nodes before linearized reading' (duration: 230.75634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:18.047558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.194009ms","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-21T17:09:18.047829Z","caller":"traceutil/trace.go:171","msg":"trace[619018346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"230.485415ms","start":"2026-04-21T17:09:17.817327Z","end":"2026-04-21T17:09:18.047812Z","steps":["trace[619018346] 'agreement among raft nodes before linearized reading' (duration: 230.037786ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:18.047644Z","caller":"traceutil/trace.go:171","msg":"trace[730165664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"230.444594ms","start":"2026-04-21T17:09:17.817177Z","end":"2026-04-21T17:09:18.047622Z","steps":["trace[730165664] 'agreement among raft nodes before linearized reading' (duration: 230.23999ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:20.549154Z","caller":"traceutil/trace.go:171","msg":"trace[1809773352] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:730; }","duration":"118.137757ms","start":"2026-04-21T17:09:20.430949Z","end":"2026-04-21T17:09:20.549087Z","steps":["trace[1809773352] 'read index received' (duration: 115.140129ms)","trace[1809773352] 'applied index is now lower than readState.Index' (duration: 2.996118ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:20.549463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.484723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-21T17:09:20.549511Z","caller":"traceutil/trace.go:171","msg":"trace[1764864136] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:703; }","duration":"118.580875ms","start":"2026-04-21T17:09:20.430915Z","end":"2026-04-21T17:09:20.549495Z","steps":["trace[1764864136] 'agreement among raft nodes before linearized reading' (duration: 118.34196ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:20.550852Z","caller":"traceutil/trace.go:171","msg":"trace[748500584] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"126.911815ms","start":"2026-04-21T17:09:20.423911Z","end":"2026-04-21T17:09:20.550823Z","steps":["trace[748500584] 'process raft request' (duration: 122.447809ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:24.295884Z","caller":"traceutil/trace.go:171","msg":"trace[727165816] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:753; }","duration":"188.95554ms","start":"2026-04-21T17:09:24.10691Z","end":"2026-04-21T17:09:24.295866Z","steps":["trace[727165816] 'read index received' (duration: 188.817707ms)","trace[727165816] 'applied index is now lower than readState.Index' (duration: 137.253µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:24.295959Z","caller":"traceutil/trace.go:171","msg":"trace[199187682] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"213.10647ms","start":"2026-04-21T17:09:24.082839Z","end":"2026-04-21T17:09:24.295946Z","steps":["trace[199187682] 'process raft request' (duration: 212.861646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:24.296044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.120103ms","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-21T17:09:24.296068Z","caller":"traceutil/trace.go:171","msg":"trace[361163300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:725; }","duration":"189.183943ms","start":"2026-04-21T17:09:24.106877Z","end":"2026-04-21T17:09:24.296061Z","steps":["trace[361163300] 'agreement among raft nodes before linearized reading' (duration: 189.081862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:24.440659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.657321ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-21T17:09:24.441169Z","caller":"traceutil/trace.go:171","msg":"trace[214956196] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"141.641386ms","start":"2026-04-21T17:09:24.299495Z","end":"2026-04-21T17:09:24.441137Z","steps":["trace[214956196] 'process raft request' (duration: 141.449503ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:24.441296Z","caller":"traceutil/trace.go:171","msg":"trace[1248395423] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"145.253145ms","start":"2026-04-21T17:09:24.295946Z","end":"2026-04-21T17:09:24.441199Z","steps":["trace[1248395423] 'read index received' (duration: 8.993002ms)","trace[1248395423] 'applied index is now lower than readState.Index' (duration: 136.252943ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:24.441642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.643316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-21T17:09:24.441724Z","caller":"traceutil/trace.go:171","msg":"trace[1963312206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:726; }","duration":"331.755238ms","start":"2026-04-21T17:09:24.109948Z","end":"2026-04-21T17:09:24.441704Z","steps":["trace[1963312206] 'agreement among raft nodes before linearized reading' (duration: 331.441132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:24.441742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.112364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-21T17:09:24.441782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:24.10994Z","time spent":"331.824009ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T17:09:24.441895Z","caller":"traceutil/trace.go:171","msg":"trace[928451924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:726; }","duration":"332.286697ms","start":"2026-04-21T17:09:24.109586Z","end":"2026-04-21T17:09:24.441872Z","steps":["trace[928451924] 'agreement among raft nodes before linearized reading' (duration: 332.026682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:24.441949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:24.10951Z","time spent":"332.42742ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T17:09:24.442322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.196651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:24.442389Z","caller":"traceutil/trace.go:171","msg":"trace[457909097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"114.305444ms","start":"2026-04-21T17:09:24.328066Z","end":"2026-04-21T17:09:24.442372Z","steps":["trace[457909097] 'agreement among raft nodes before linearized reading' (duration: 114.171801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:29.827469Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225655340878194,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T17:09:29.828647Z","caller":"traceutil/trace.go:171","msg":"trace[825805641] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"501.363613ms","start":"2026-04-21T17:09:29.327265Z","end":"2026-04-21T17:09:29.828629Z","steps":["trace[825805641] 'read index received' (duration: 501.204089ms)","trace[825805641] 'applied index is now lower than readState.Index' (duration: 158.734µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:29.828765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.481275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:29.82874Z","caller":"traceutil/trace.go:171","msg":"trace[2055158795] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"666.346088ms","start":"2026-04-21T17:09:29.162366Z","end":"2026-04-21T17:09:29.828712Z","steps":["trace[2055158795] 'process raft request' (duration: 666.135374ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:29.828801Z","caller":"traceutil/trace.go:171","msg":"trace[1100442637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"501.560885ms","start":"2026-04-21T17:09:29.327232Z","end":"2026-04-21T17:09:29.828793Z","steps":["trace[1100442637] 'agreement among raft nodes before linearized reading' (duration: 501.492364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:29.82883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:29.327218Z","time spent":"501.605875ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:09:29.828906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:29.162351Z","time spent":"666.47178ms","remote":"127.0.0.1:52500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:09:29.829108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.3278ms","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-21T17:09:29.829224Z","caller":"traceutil/trace.go:171","msg":"trace[2076172588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:761; }","duration":"279.508195ms","start":"2026-04-21T17:09:29.549692Z","end":"2026-04-21T17:09:29.8292Z","steps":["trace[2076172588] 'agreement among raft nodes before linearized reading' (duration: 279.162549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:30.083332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.17112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:09:30.083443Z","caller":"traceutil/trace.go:171","msg":"trace[709680262] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"247.256999ms","start":"2026-04-21T17:09:29.836171Z","end":"2026-04-21T17:09:30.083428Z","steps":["trace[709680262] 'process raft request' (duration: 142.906557ms)","trace[709680262] 'compare' (duration: 104.065067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:30.579326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.588549ms","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-21T17:09:30.57967Z","caller":"traceutil/trace.go:171","msg":"trace[146114812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"111.935956ms","start":"2026-04-21T17:09:30.467642Z","end":"2026-04-21T17:09:30.579578Z","steps":["trace[146114812] 'range keys from in-memory index tree' (duration: 111.243132ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:38.257386Z","caller":"traceutil/trace.go:171","msg":"trace[427257433] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"114.859906ms","start":"2026-04-21T17:09:38.1425Z","end":"2026-04-21T17:09:38.257359Z","steps":["trace[427257433] 'read index received' (duration: 114.740064ms)","trace[427257433] 'applied index is now lower than readState.Index' (duration: 119.122µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:38.257441Z","caller":"traceutil/trace.go:171","msg":"trace[1530028515] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"341.107867ms","start":"2026-04-21T17:09:37.916294Z","end":"2026-04-21T17:09:38.257402Z","steps":["trace[1530028515] 'process raft request' (duration: 340.957553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:38.25758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.315363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-21T17:09:38.257581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.080861ms","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-21T17:09:38.257607Z","caller":"traceutil/trace.go:171","msg":"trace[361187881] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:846; }","duration":"108.380193ms","start":"2026-04-21T17:09:38.149219Z","end":"2026-04-21T17:09:38.257599Z","steps":["trace[361187881] 'agreement among raft nodes before linearized reading' (duration: 108.269581ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:38.257628Z","caller":"traceutil/trace.go:171","msg":"trace[14628208] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:846; }","duration":"115.161893ms","start":"2026-04-21T17:09:38.142454Z","end":"2026-04-21T17:09:38.257616Z","steps":["trace[14628208] 'agreement among raft nodes before linearized reading' (duration: 114.994649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:38.257611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:37.916254Z","time spent":"341.288899ms","remote":"127.0.0.1:52384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":802,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T17:09:38.490341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.77884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T17:09:38.490431Z","caller":"traceutil/trace.go:171","msg":"trace[1939826860] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:849; }","duration":"101.962333ms","start":"2026-04-21T17:09:38.38845Z","end":"2026-04-21T17:09:38.490413Z","steps":["trace[1939826860] 'count revisions from in-memory index tree' (duration: 101.666198ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:38.640105Z","caller":"traceutil/trace.go:171","msg":"trace[2078863595] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"102.553144ms","start":"2026-04-21T17:09:38.537524Z","end":"2026-04-21T17:09:38.640077Z","steps":["trace[2078863595] 'process raft request' (duration: 102.33647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:45.400119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.194183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:09:45.40021Z","caller":"traceutil/trace.go:171","msg":"trace[1320056267] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"775.707239ms","start":"2026-04-21T17:09:44.624488Z","end":"2026-04-21T17:09:45.400195Z","steps":["trace[1320056267] 'read index received' (duration: 256.397884ms)","trace[1320056267] 'applied index is now lower than readState.Index' (duration: 519.307984ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:45.400267Z","caller":"traceutil/trace.go:171","msg":"trace[646516532] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"1.062697838s","start":"2026-04-21T17:09:44.337554Z","end":"2026-04-21T17:09:45.400252Z","steps":["trace[646516532] 'process raft request' (duration: 543.321141ms)","trace[646516532] 'compare' (duration: 519.037751ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:45.400308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:44.337522Z","time spent":"1.062767418s","remote":"127.0.0.1:52384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T17:09:45.40054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.046126ms","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-21T17:09:45.40057Z","caller":"traceutil/trace.go:171","msg":"trace[1725604164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:931; }","duration":"776.104797ms","start":"2026-04-21T17:09:44.624458Z","end":"2026-04-21T17:09:45.400563Z","steps":["trace[1725604164] 'agreement among raft nodes before linearized reading' (duration: 775.973005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:45.40059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:44.624444Z","time spent":"776.140877ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T17:09:45.431488Z","caller":"traceutil/trace.go:171","msg":"trace[541188438] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"767.028296ms","start":"2026-04-21T17:09:44.664439Z","end":"2026-04-21T17:09:45.431468Z","steps":["trace[541188438] 'process raft request' (duration: 766.913024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:45.431626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:44.66442Z","time spent":"767.134679ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:09:45.431795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.34792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:45.431885Z","caller":"traceutil/trace.go:171","msg":"trace[2021883123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"103.486392ms","start":"2026-04-21T17:09:45.328381Z","end":"2026-04-21T17:09:45.431868Z","steps":["trace[2021883123] 'agreement among raft nodes before linearized reading' (duration: 103.313588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:45.431911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.8697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-21T17:09:45.431914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.723027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T17:09:45.431939Z","caller":"traceutil/trace.go:171","msg":"trace[1626622420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"670.921001ms","start":"2026-04-21T17:09:44.761008Z","end":"2026-04-21T17:09:45.431929Z","steps":["trace[1626622420] 'agreement among raft nodes before linearized reading' (duration: 670.852821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:45.431967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:44.760989Z","time spent":"670.969342ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T17:09:45.431964Z","caller":"traceutil/trace.go:171","msg":"trace[9121423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:932; }","duration":"670.821369ms","start":"2026-04-21T17:09:44.761127Z","end":"2026-04-21T17:09:45.431948Z","steps":["trace[9121423] 'agreement among raft nodes before linearized reading' (duration: 670.672927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:45.432017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:44.761103Z","time spent":"670.898301ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T17:09:45.431828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.530722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-6zn7g\" ","response":"range_response_count:1 size:3611"} {"level":"info","ts":"2026-04-21T17:09:45.432115Z","caller":"traceutil/trace.go:171","msg":"trace[519297528] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-6zn7g; range_end:; response_count:1; response_revision:932; }","duration":"192.848429ms","start":"2026-04-21T17:09:45.239257Z","end":"2026-04-21T17:09:45.432106Z","steps":["trace[519297528] 'agreement among raft nodes before linearized reading' (duration: 192.456011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:45.431796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.945551ms","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-21T17:09:45.432248Z","caller":"traceutil/trace.go:171","msg":"trace[1743553507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:932; }","duration":"670.463903ms","start":"2026-04-21T17:09:44.761776Z","end":"2026-04-21T17:09:45.43224Z","steps":["trace[1743553507] 'agreement among raft nodes before linearized reading' (duration: 669.805511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:45.43228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:44.761763Z","time spent":"670.507543ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T17:09:45.432423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.998586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:09:45.43247Z","caller":"traceutil/trace.go:171","msg":"trace[1652076844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"734.077396ms","start":"2026-04-21T17:09:44.698382Z","end":"2026-04-21T17:09:45.432459Z","steps":["trace[1652076844] 'agreement among raft nodes before linearized reading' (duration: 733.103388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:45.43249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:44.698363Z","time spent":"734.122058ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T17:09:45.569173Z","caller":"traceutil/trace.go:171","msg":"trace[1975899567] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"165.081267ms","start":"2026-04-21T17:09:45.404063Z","end":"2026-04-21T17:09:45.569144Z","steps":["trace[1975899567] 'process raft request' (duration: 103.246318ms)","trace[1975899567] 'compare' (duration: 61.688657ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:09:45.57429Z","caller":"traceutil/trace.go:171","msg":"trace[1641561368] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"136.055563ms","start":"2026-04-21T17:09:45.438209Z","end":"2026-04-21T17:09:45.574264Z","steps":["trace[1641561368] 'process raft request' (duration: 136.012822ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:45.57435Z","caller":"traceutil/trace.go:171","msg":"trace[1772372453] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"136.757946ms","start":"2026-04-21T17:09:45.437585Z","end":"2026-04-21T17:09:45.574343Z","steps":["trace[1772372453] 'process raft request' (duration: 136.585032ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:45.574298Z","caller":"traceutil/trace.go:171","msg":"trace[1516994014] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"136.405729ms","start":"2026-04-21T17:09:45.43788Z","end":"2026-04-21T17:09:45.574285Z","steps":["trace[1516994014] 'process raft request' (duration: 136.314007ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:45.574291Z","caller":"traceutil/trace.go:171","msg":"trace[1615699846] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"137.897938ms","start":"2026-04-21T17:09:45.436356Z","end":"2026-04-21T17:09:45.574254Z","steps":["trace[1615699846] 'process raft request' (duration: 137.741534ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:56.033356Z","caller":"traceutil/trace.go:171","msg":"trace[1982458814] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"137.826467ms","start":"2026-04-21T17:09:55.895511Z","end":"2026-04-21T17:09:56.033337Z","steps":["trace[1982458814] 'process raft request' (duration: 137.688905ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:09:56.231261Z","caller":"traceutil/trace.go:171","msg":"trace[378751900] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"334.939469ms","start":"2026-04-21T17:09:55.896295Z","end":"2026-04-21T17:09:56.231234Z","steps":["trace[378751900] 'process raft request' (duration: 303.348961ms)","trace[378751900] 'compare' (duration: 31.14544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:09:56.231426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:55.896277Z","time spent":"335.052531ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:09:56.23141Z","caller":"traceutil/trace.go:171","msg":"trace[1302018069] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"334.900248ms","start":"2026-04-21T17:09:55.896483Z","end":"2026-04-21T17:09:56.231383Z","steps":["trace[1302018069] 'process raft request' (duration: 334.684984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:09:56.23157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:09:55.896475Z","time spent":"335.02258ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:09:58.142905Z","caller":"traceutil/trace.go:171","msg":"trace[973660906] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"102.199941ms","start":"2026-04-21T17:09:58.040689Z","end":"2026-04-21T17:09:58.142888Z","steps":["trace[973660906] 'process raft request' (duration: 102.081679ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:01.702102Z","caller":"traceutil/trace.go:171","msg":"trace[1921719065] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"102.26561ms","start":"2026-04-21T17:10:01.599809Z","end":"2026-04-21T17:10:01.702074Z","steps":["trace[1921719065] 'process raft request' (duration: 102.075796ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:10.851661Z","caller":"traceutil/trace.go:171","msg":"trace[1878731296] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"143.617224ms","start":"2026-04-21T17:10:10.708018Z","end":"2026-04-21T17:10:10.851635Z","steps":["trace[1878731296] 'process raft request' (duration: 143.445611ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:12.877634Z","caller":"traceutil/trace.go:171","msg":"trace[1014558627] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"353.044517ms","start":"2026-04-21T17:10:12.524563Z","end":"2026-04-21T17:10:12.877608Z","steps":["trace[1014558627] 'process raft request' (duration: 352.920795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:10:12.87799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:10:12.524549Z","time spent":"353.326023ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:10:14.680885Z","caller":"traceutil/trace.go:171","msg":"trace[444282792] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"116.256544ms","start":"2026-04-21T17:10:14.564603Z","end":"2026-04-21T17:10:14.680859Z","steps":["trace[444282792] 'process raft request' (duration: 116.062631ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:16.89657Z","caller":"traceutil/trace.go:171","msg":"trace[922691666] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"205.481256ms","start":"2026-04-21T17:10:16.691055Z","end":"2026-04-21T17:10:16.896537Z","steps":["trace[922691666] 'process raft request' (duration: 204.761614ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:17.072968Z","caller":"traceutil/trace.go:171","msg":"trace[224076854] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"145.451369ms","start":"2026-04-21T17:10:16.92749Z","end":"2026-04-21T17:10:17.072941Z","steps":["trace[224076854] 'process raft request' (duration: 69.632583ms)","trace[224076854] 'compare' (duration: 75.252496ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:10:18.08737Z","caller":"traceutil/trace.go:171","msg":"trace[602080859] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"195.923668ms","start":"2026-04-21T17:10:17.89141Z","end":"2026-04-21T17:10:18.087334Z","steps":["trace[602080859] 'process raft request' (duration: 195.539461ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:19.232242Z","caller":"traceutil/trace.go:171","msg":"trace[1838544769] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"101.763382ms","start":"2026-04-21T17:10:19.130456Z","end":"2026-04-21T17:10:19.232219Z","steps":["trace[1838544769] 'process raft request' (duration: 49.117994ms)","trace[1838544769] 'compare' (duration: 52.536766ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:10:21.228817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.611423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-21T17:10:21.228912Z","caller":"traceutil/trace.go:171","msg":"trace[103737045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"110.764657ms","start":"2026-04-21T17:10:21.118124Z","end":"2026-04-21T17:10:21.228889Z","steps":["trace[103737045] 'range keys from in-memory index tree' (duration: 110.357408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:10:23.486515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.776536ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-21T17:10:23.486642Z","caller":"traceutil/trace.go:171","msg":"trace[703503316] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"199.430814ms","start":"2026-04-21T17:10:23.287195Z","end":"2026-04-21T17:10:23.486626Z","steps":["trace[703503316] 'read index received' (duration: 33.398834ms)","trace[703503316] 'applied index is now lower than readState.Index' (duration: 166.03062ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:10:23.486758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.783846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:10:23.486788Z","caller":"traceutil/trace.go:171","msg":"trace[1915173616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1068; }","duration":"159.848138ms","start":"2026-04-21T17:10:23.326932Z","end":"2026-04-21T17:10:23.48678Z","steps":["trace[1915173616] 'agreement among raft nodes before linearized reading' (duration: 159.780806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:10:23.486873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.663698ms","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-21T17:10:23.486938Z","caller":"traceutil/trace.go:171","msg":"trace[1113844222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1068; }","duration":"199.78188ms","start":"2026-04-21T17:10:23.287144Z","end":"2026-04-21T17:10:23.486926Z","steps":["trace[1113844222] 'agreement among raft nodes before linearized reading' (duration: 199.555096ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:23.889044Z","caller":"traceutil/trace.go:171","msg":"trace[1421770711] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"257.188104ms","start":"2026-04-21T17:10:23.63182Z","end":"2026-04-21T17:10:23.889008Z","steps":["trace[1421770711] 'process raft request' (duration: 256.94107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:10:24.112735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.562575ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:10:24.1129Z","caller":"traceutil/trace.go:171","msg":"trace[1926606714] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1070; }","duration":"150.754948ms","start":"2026-04-21T17:10:23.962124Z","end":"2026-04-21T17:10:24.112879Z","steps":["trace[1926606714] 'range keys from in-memory index tree' (duration: 150.540714ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:24.332134Z","caller":"traceutil/trace.go:171","msg":"trace[300930287] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"111.599109ms","start":"2026-04-21T17:10:24.220511Z","end":"2026-04-21T17:10:24.33211Z","steps":["trace[300930287] 'process raft request' (duration: 111.394765ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:24.519747Z","caller":"traceutil/trace.go:171","msg":"trace[1098184667] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"181.88407ms","start":"2026-04-21T17:10:24.337803Z","end":"2026-04-21T17:10:24.519687Z","steps":["trace[1098184667] 'process raft request' (duration: 121.901098ms)","trace[1098184667] 'compare' (duration: 59.707896ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:10:24.876094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.492183ms","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-21T17:10:24.876182Z","caller":"traceutil/trace.go:171","msg":"trace[1180579085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1072; }","duration":"193.656715ms","start":"2026-04-21T17:10:24.682506Z","end":"2026-04-21T17:10:24.876162Z","steps":["trace[1180579085] 'range keys from in-memory index tree' (duration: 193.232459ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:27.234464Z","caller":"traceutil/trace.go:171","msg":"trace[647786804] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"152.228383ms","start":"2026-04-21T17:10:27.082198Z","end":"2026-04-21T17:10:27.234427Z","steps":["trace[647786804] 'read index received' (duration: 149.996012ms)","trace[647786804] 'applied index is now lower than readState.Index' (duration: 2.230581ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:10:27.234541Z","caller":"traceutil/trace.go:171","msg":"trace[1979511635] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"272.638109ms","start":"2026-04-21T17:10:26.961848Z","end":"2026-04-21T17:10:27.234486Z","steps":["trace[1979511635] 'process raft request' (duration: 271.260624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:10:27.235203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.962355ms","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-21T17:10:27.235343Z","caller":"traceutil/trace.go:171","msg":"trace[1233260954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1079; }","duration":"153.16812ms","start":"2026-04-21T17:10:27.082136Z","end":"2026-04-21T17:10:27.235305Z","steps":["trace[1233260954] 'agreement among raft nodes before linearized reading' (duration: 152.505138ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:27.422205Z","caller":"traceutil/trace.go:171","msg":"trace[398249703] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"126.367629ms","start":"2026-04-21T17:10:27.295797Z","end":"2026-04-21T17:10:27.422165Z","steps":["trace[398249703] 'process raft request' (duration: 124.451513ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:46.650084Z","caller":"traceutil/trace.go:171","msg":"trace[1090136401] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"160.796541ms","start":"2026-04-21T17:10:46.48927Z","end":"2026-04-21T17:10:46.650066Z","steps":["trace[1090136401] 'process raft request' (duration: 160.681948ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:53.463335Z","caller":"traceutil/trace.go:171","msg":"trace[1019358253] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1213; }","duration":"133.651635ms","start":"2026-04-21T17:10:53.329642Z","end":"2026-04-21T17:10:53.463293Z","steps":["trace[1019358253] 'read index received' (duration: 82.338592ms)","trace[1019358253] 'applied index is now lower than readState.Index' (duration: 51.307382ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:10:53.463542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.865562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:10:53.46367Z","caller":"traceutil/trace.go:171","msg":"trace[738412718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1164; }","duration":"134.037597ms","start":"2026-04-21T17:10:53.329607Z","end":"2026-04-21T17:10:53.463645Z","steps":["trace[738412718] 'agreement among raft nodes before linearized reading' (duration: 133.8149ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:10:57.775871Z","caller":"traceutil/trace.go:171","msg":"trace[26323959] linearizableReadLoop","detail":"{readStateIndex:1229; appliedIndex:1228; }","duration":"115.208799ms","start":"2026-04-21T17:10:57.660636Z","end":"2026-04-21T17:10:57.775845Z","steps":["trace[26323959] 'read index received' (duration: 114.974421ms)","trace[26323959] 'applied index is now lower than readState.Index' (duration: 230.008µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:10:57.775987Z","caller":"traceutil/trace.go:171","msg":"trace[1605040955] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"184.823026ms","start":"2026-04-21T17:10:57.591126Z","end":"2026-04-21T17:10:57.775949Z","steps":["trace[1605040955] 'process raft request' (duration: 184.548047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:10:57.776153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.503037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T17:10:57.776206Z","caller":"traceutil/trace.go:171","msg":"trace[967077982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1179; }","duration":"115.623143ms","start":"2026-04-21T17:10:57.660564Z","end":"2026-04-21T17:10:57.776187Z","steps":["trace[967077982] 'agreement among raft nodes before linearized reading' (duration: 115.392046ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:11:05.213031Z","caller":"traceutil/trace.go:171","msg":"trace[1149869252] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"195.824261ms","start":"2026-04-21T17:11:05.017175Z","end":"2026-04-21T17:11:05.212999Z","steps":["trace[1149869252] 'process raft request' (duration: 195.581153ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:11:09.04273Z","caller":"traceutil/trace.go:171","msg":"trace[1435444003] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"244.79012ms","start":"2026-04-21T17:11:08.797915Z","end":"2026-04-21T17:11:09.042705Z","steps":["trace[1435444003] 'process raft request' (duration: 244.514912ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:11:09.342992Z","caller":"traceutil/trace.go:171","msg":"trace[908828687] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"102.707603ms","start":"2026-04-21T17:11:09.240249Z","end":"2026-04-21T17:11:09.342956Z","steps":["trace[908828687] 'process raft request' (duration: 102.412513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:11:10.064472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.598763ms","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-21T17:11:10.064563Z","caller":"traceutil/trace.go:171","msg":"trace[1415967070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1218; }","duration":"143.764698ms","start":"2026-04-21T17:11:09.920779Z","end":"2026-04-21T17:11:10.064544Z","steps":["trace[1415967070] 'range keys from in-memory index tree' (duration: 143.429767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:11:10.064744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.547017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T17:11:10.064858Z","caller":"traceutil/trace.go:171","msg":"trace[1246072408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1218; }","duration":"123.725242ms","start":"2026-04-21T17:11:09.941112Z","end":"2026-04-21T17:11:10.064837Z","steps":["trace[1246072408] 'range keys from in-memory index tree' (duration: 123.119543ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:11:10.175218Z","caller":"traceutil/trace.go:171","msg":"trace[1425688520] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"105.457226ms","start":"2026-04-21T17:11:10.069728Z","end":"2026-04-21T17:11:10.175186Z","steps":["trace[1425688520] 'process raft request' (duration: 105.174528ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:11:10.306227Z","caller":"traceutil/trace.go:171","msg":"trace[445893507] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"183.711434ms","start":"2026-04-21T17:11:10.122471Z","end":"2026-04-21T17:11:10.306182Z","steps":["trace[445893507] 'process raft request' (duration: 183.59337ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:11:10.306954Z","caller":"traceutil/trace.go:171","msg":"trace[806972843] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"234.292527ms","start":"2026-04-21T17:11:10.072626Z","end":"2026-04-21T17:11:10.306919Z","steps":["trace[806972843] 'process raft request' (duration: 216.391998ms)","trace[806972843] 'compare' (duration: 16.856798ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:11:11.21554Z","caller":"traceutil/trace.go:171","msg":"trace[1863530569] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"166.984546ms","start":"2026-04-21T17:11:11.048523Z","end":"2026-04-21T17:11:11.215508Z","steps":["trace[1863530569] 'process raft request' (duration: 166.751479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:11:27.060635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.498906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T17:11:27.060817Z","caller":"traceutil/trace.go:171","msg":"trace[27737302] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1307; }","duration":"184.630879ms","start":"2026-04-21T17:11:26.876083Z","end":"2026-04-21T17:11:27.060714Z","steps":["trace[27737302] 'count revisions from in-memory index tree' (duration: 184.402633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:11:33.609506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.865056ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-21T17:11:33.609958Z","caller":"traceutil/trace.go:171","msg":"trace[972317172] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"226.822982ms","start":"2026-04-21T17:11:33.38309Z","end":"2026-04-21T17:11:33.609913Z","steps":["trace[972317172] 'process raft request' (duration: 226.531263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:11:33.705886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.862318ms","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-21T17:11:33.706032Z","caller":"traceutil/trace.go:171","msg":"trace[144567723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1334; }","duration":"155.065173ms","start":"2026-04-21T17:11:33.550936Z","end":"2026-04-21T17:11:33.706001Z","steps":["trace[144567723] 'agreement among raft nodes before linearized reading' (duration: 58.954124ms)","trace[144567723] 'range keys from in-memory index tree' (duration: 95.811211ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:11:33.908773Z","caller":"traceutil/trace.go:171","msg":"trace[37980377] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"140.022748ms","start":"2026-04-21T17:11:33.768714Z","end":"2026-04-21T17:11:33.908736Z","steps":["trace[37980377] 'process raft request' (duration: 101.796592ms)","trace[37980377] 'compare' (duration: 38.074091ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:11:35.042928Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225655340879586,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T17:11:35.049488Z","caller":"traceutil/trace.go:171","msg":"trace[1419830491] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1396; }","duration":"508.115373ms","start":"2026-04-21T17:11:34.541342Z","end":"2026-04-21T17:11:35.049457Z","steps":["trace[1419830491] 'read index received' (duration: 507.097634ms)","trace[1419830491] 'applied index is now lower than readState.Index' (duration: 1.016279ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:11:35.050115Z","caller":"traceutil/trace.go:171","msg":"trace[1826887548] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"583.411048ms","start":"2026-04-21T17:11:34.466661Z","end":"2026-04-21T17:11:35.050072Z","steps":["trace[1826887548] 'process raft request' (duration: 581.813443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:11:35.050268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.305069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T17:11:35.050294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.910615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T17:11:35.050373Z","caller":"traceutil/trace.go:171","msg":"trace[695372291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1338; }","duration":"352.476094ms","start":"2026-04-21T17:11:34.697873Z","end":"2026-04-21T17:11:35.050349Z","steps":["trace[695372291] 'agreement among raft nodes before linearized reading' (duration: 352.288619ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:11:35.050382Z","caller":"traceutil/trace.go:171","msg":"trace[1546435538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1338; }","duration":"509.07921ms","start":"2026-04-21T17:11:34.541284Z","end":"2026-04-21T17:11:35.050363Z","steps":["trace[1546435538] 'agreement among raft nodes before linearized reading' (duration: 508.772852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:11:35.050542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:11:34.541254Z","time spent":"509.274927ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T17:11:35.05056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.126564ms","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-21T17:11:35.050638Z","caller":"traceutil/trace.go:171","msg":"trace[1172948083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1338; }","duration":"310.234077ms","start":"2026-04-21T17:11:34.740384Z","end":"2026-04-21T17:11:35.050619Z","steps":["trace[1172948083] 'agreement among raft nodes before linearized reading' (duration: 309.99772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:11:35.050677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:11:34.740359Z","time spent":"310.311088ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T17:11:35.05031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:11:34.466626Z","time spent":"583.581722ms","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:11:35.051023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:11:34.697845Z","time spent":"352.582427ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T17:11:35.185481Z","caller":"traceutil/trace.go:171","msg":"trace[454280242] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"127.262517ms","start":"2026-04-21T17:11:35.058189Z","end":"2026-04-21T17:11:35.185452Z","steps":["trace[454280242] 'process raft request' (duration: 127.065971ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:11:45.28786Z","caller":"traceutil/trace.go:171","msg":"trace[1511111231] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"128.354215ms","start":"2026-04-21T17:11:45.159453Z","end":"2026-04-21T17:11:45.287807Z","steps":["trace[1511111231] 'process raft request' (duration: 128.065877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:11:46.185016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.998543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-account-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-04-21T17:11:46.185114Z","caller":"traceutil/trace.go:171","msg":"trace[541826928] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-account-controller; range_end:; response_count:1; response_revision:1380; }","duration":"242.209768ms","start":"2026-04-21T17:11:45.942882Z","end":"2026-04-21T17:11:46.185091Z","steps":["trace[541826928] 'range keys from in-memory index tree' (duration: 241.858389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:11:46.185119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.551116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-controller\" ","response":"range_response_count:1 size:724"} {"level":"info","ts":"2026-04-21T17:11:46.185209Z","caller":"traceutil/trace.go:171","msg":"trace[1331556056] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-controller; range_end:; response_count:1; response_revision:1380; }","duration":"172.70232ms","start":"2026-04-21T17:11:46.012484Z","end":"2026-04-21T17:11:46.185186Z","steps":["trace[1331556056] 'range keys from in-memory index tree' (duration: 172.397481ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:11:46.381121Z","caller":"traceutil/trace.go:171","msg":"trace[16511366] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1442; }","duration":"108.573803ms","start":"2026-04-21T17:11:46.272518Z","end":"2026-04-21T17:11:46.381092Z","steps":["trace[16511366] 'read index received' (duration: 108.380277ms)","trace[16511366] 'applied index is now lower than readState.Index' (duration: 191.846µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:11:46.381272Z","caller":"traceutil/trace.go:171","msg":"trace[1715394149] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"191.814816ms","start":"2026-04-21T17:11:46.189445Z","end":"2026-04-21T17:11:46.381259Z","steps":["trace[1715394149] 'process raft request' (duration: 191.485117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:11:46.381465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.922232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:990"} {"level":"info","ts":"2026-04-21T17:11:46.381501Z","caller":"traceutil/trace.go:171","msg":"trace[643400041] range","detail":"{range_begin:/registry/clusterroles/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:1381; }","duration":"109.007873ms","start":"2026-04-21T17:11:46.272482Z","end":"2026-04-21T17:11:46.38149Z","steps":["trace[643400041] 'agreement among raft nodes before linearized reading' (duration: 108.91381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:11:46.38181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.033597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-controller\" ","response":"range_response_count:1 size:724"} {"level":"info","ts":"2026-04-21T17:11:46.381935Z","caller":"traceutil/trace.go:171","msg":"trace[676225099] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-controller; range_end:; response_count:1; response_revision:1381; }","duration":"104.188891ms","start":"2026-04-21T17:11:46.277716Z","end":"2026-04-21T17:11:46.381905Z","steps":["trace[676225099] 'agreement among raft nodes before linearized reading' (duration: 103.998785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:11:58.352885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.237289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:70 size:51820"} {"level":"info","ts":"2026-04-21T17:11:58.352973Z","caller":"traceutil/trace.go:171","msg":"trace[574281434] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:70; response_revision:1420; }","duration":"107.384993ms","start":"2026-04-21T17:11:58.24557Z","end":"2026-04-21T17:11:58.352955Z","steps":["trace[574281434] 'range keys from in-memory index tree' (duration: 106.632523ms)"],"step_count":1}