{"level":"warn","ts":"2026-05-07T03:20:40.438712Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T03:20:40.438793Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-05-07T03:20:40.438855Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T03:20:40.438866Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-07T03:20:40.438884Z","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-05-07T03:20:40.439484Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-05-07T03:20:40.439548Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-05-07T03:20:40.447019Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.292163ms"} {"level":"info","ts":"2026-05-07T03:20:40.467545Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-05-07T03:20:40.467619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T03:20:40.467644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-05-07T03:20:40.467669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T03:20:40.467687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-05-07T03:20:40.467731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-05-07T03:20:40.478902Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T03:20:40.481563Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T03:20:40.483569Z","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-05-07T03:20:40.489064Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T03:20:40.48945Z","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-05-07T03:20:40.489663Z","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-05-07T03:20:40.489615Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T03:20:40.489683Z","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-05-07T03:20:40.493046Z","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-05-07T03:20:40.493191Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-07T03:20:40.493238Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-07T03:20:40.493396Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T03:20:40.493454Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T03:20:40.531406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-05-07T03:20:40.531731Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-07T03:20:40.868401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T03:20:40.868462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T03:20:40.868501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-05-07T03:20:40.86852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-05-07T03:20:40.868531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-07T03:20:40.868546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-05-07T03:20:40.868738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-07T03:20:40.945976Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:20:40.948748Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T03:20:40.949538Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:20:40.949596Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:20:40.949677Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:20:40.949701Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:20:40.949743Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:20:40.949915Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T03:20:40.950009Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T03:20:40.955878Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T03:20:40.956548Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-05-07T03:20:43.691788Z","caller":"traceutil/trace.go:171","msg":"trace[1299955396] transaction","detail":"{read_only:false; response_revision:157; number_of_response:1; }","duration":"190.649536ms","start":"2026-05-07T03:20:43.501104Z","end":"2026-05-07T03:20:43.691754Z","steps":["trace[1299955396] 'process raft request' (duration: 126.820513ms)","trace[1299955396] 'compare' (duration: 62.534071ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:43.691904Z","caller":"traceutil/trace.go:171","msg":"trace[111132637] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"165.735985ms","start":"2026-05-07T03:20:43.526145Z","end":"2026-05-07T03:20:43.691881Z","steps":["trace[111132637] 'process raft request' (duration: 165.582714ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:54.576379Z","caller":"traceutil/trace.go:171","msg":"trace[523547675] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"149.538761ms","start":"2026-05-07T03:20:54.426814Z","end":"2026-05-07T03:20:54.576352Z","steps":["trace[523547675] 'read index received' (duration: 52.332316ms)","trace[523547675] 'applied index is now lower than readState.Index' (duration: 97.205415ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:54.576454Z","caller":"traceutil/trace.go:171","msg":"trace[1199879484] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"197.069721ms","start":"2026-05-07T03:20:54.379334Z","end":"2026-05-07T03:20:54.576404Z","steps":["trace[1199879484] 'process raft request' (duration: 99.832075ms)","trace[1199879484] 'compare' (duration: 97.042394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:20:54.576538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.720173ms","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-05-07T03:20:54.576622Z","caller":"traceutil/trace.go:171","msg":"trace[279495044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:402; }","duration":"149.857284ms","start":"2026-05-07T03:20:54.426751Z","end":"2026-05-07T03:20:54.576609Z","steps":["trace[279495044] 'agreement among raft nodes before linearized reading' (duration: 149.702223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:54.576706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.283132ms","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-05-07T03:20:54.576806Z","caller":"traceutil/trace.go:171","msg":"trace[875386228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:402; }","duration":"148.377963ms","start":"2026-05-07T03:20:54.428388Z","end":"2026-05-07T03:20:54.576766Z","steps":["trace[875386228] 'agreement among raft nodes before linearized reading' (duration: 148.183661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:54.576857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.561684ms","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-05-07T03:20:54.576958Z","caller":"traceutil/trace.go:171","msg":"trace[43949870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:402; }","duration":"140.696695ms","start":"2026-05-07T03:20:54.436236Z","end":"2026-05-07T03:20:54.576933Z","steps":["trace[43949870] 'agreement among raft nodes before linearized reading' (duration: 140.492223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:54.83094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.016433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:20:54.831163Z","caller":"traceutil/trace.go:171","msg":"trace[2091646971] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:418; }","duration":"198.851664ms","start":"2026-05-07T03:20:54.63229Z","end":"2026-05-07T03:20:54.831141Z","steps":["trace[2091646971] 'read index received' (duration: 50.924345ms)","trace[2091646971] 'applied index is now lower than readState.Index' (duration: 147.926189ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:54.831326Z","caller":"traceutil/trace.go:171","msg":"trace[277138317] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"251.018699ms","start":"2026-05-07T03:20:54.58028Z","end":"2026-05-07T03:20:54.831299Z","steps":["trace[277138317] 'process raft request' (duration: 102.973309ms)","trace[277138317] 'compare' (duration: 146.896872ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:54.831335Z","caller":"traceutil/trace.go:171","msg":"trace[54550007] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"250.791087ms","start":"2026-05-07T03:20:54.580487Z","end":"2026-05-07T03:20:54.831278Z","steps":["trace[54550007] 'process raft request' (duration: 250.571316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:54.831752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.46087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-07T03:20:54.83181Z","caller":"traceutil/trace.go:171","msg":"trace[538204750] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:405; }","duration":"199.55704ms","start":"2026-05-07T03:20:54.632238Z","end":"2026-05-07T03:20:54.831795Z","steps":["trace[538204750] 'agreement among raft nodes before linearized reading' (duration: 199.457779ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:55.334404Z","caller":"traceutil/trace.go:171","msg":"trace[555911675] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"310.342208ms","start":"2026-05-07T03:20:55.024038Z","end":"2026-05-07T03:20:55.33438Z","steps":["trace[555911675] 'process raft request' (duration: 310.202877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:55.334552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:55.024016Z","time spent":"310.461439ms","remote":"127.0.0.1:36312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:20:56.614443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605641898038925,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T03:20:56.635446Z","caller":"traceutil/trace.go:171","msg":"trace[924366088] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"634.707442ms","start":"2026-05-07T03:20:56.000718Z","end":"2026-05-07T03:20:56.635426Z","steps":["trace[924366088] 'process raft request' (duration: 634.567141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:56.635571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:56.000695Z","time spent":"634.814913ms","remote":"127.0.0.1:36312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:20:56.973805Z","caller":"traceutil/trace.go:171","msg":"trace[607433434] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:421; }","duration":"859.868319ms","start":"2026-05-07T03:20:56.113912Z","end":"2026-05-07T03:20:56.97378Z","steps":["trace[607433434] 'read index received' (duration: 521.342653ms)","trace[607433434] 'applied index is now lower than readState.Index' (duration: 338.524176ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:56.973952Z","caller":"traceutil/trace.go:171","msg":"trace[1397440319] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"692.24325ms","start":"2026-05-07T03:20:56.281675Z","end":"2026-05-07T03:20:56.973919Z","steps":["trace[1397440319] 'process raft request' (duration: 600.525933ms)","trace[1397440319] 'compare' (duration: 90.610637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:20:56.974042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.415832ms","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-05-07T03:20:56.974128Z","caller":"traceutil/trace.go:171","msg":"trace[759905383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"340.545932ms","start":"2026-05-07T03:20:56.633565Z","end":"2026-05-07T03:20:56.974111Z","steps":["trace[759905383] 'agreement among raft nodes before linearized reading' (duration: 340.417171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:56.974136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:56.281647Z","time spent":"692.375901ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:20:56.974123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.077688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-07T03:20:56.974222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.53268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-05-07T03:20:56.974173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:56.633545Z","time spent":"340.620144ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T03:20:56.973988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.06675ms","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-05-07T03:20:56.974299Z","caller":"traceutil/trace.go:171","msg":"trace[1104834263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"860.431804ms","start":"2026-05-07T03:20:56.11386Z","end":"2026-05-07T03:20:56.974292Z","steps":["trace[1104834263] 'agreement among raft nodes before linearized reading' (duration: 860.055901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:56.974333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:56.113839Z","time spent":"860.484144ms","remote":"127.0.0.1:36378","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-05-07T03:20:56.974266Z","caller":"traceutil/trace.go:171","msg":"trace[1814246386] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:408; }","duration":"335.636362ms","start":"2026-05-07T03:20:56.63861Z","end":"2026-05-07T03:20:56.974247Z","steps":["trace[1814246386] 'agreement among raft nodes before linearized reading' (duration: 335.583911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:56.974441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:56.638585Z","time spent":"335.844763ms","remote":"127.0.0.1:36312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-07T03:20:56.974452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.721574ms","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-05-07T03:20:56.974508Z","caller":"traceutil/trace.go:171","msg":"trace[1210026985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"138.804086ms","start":"2026-05-07T03:20:56.835697Z","end":"2026-05-07T03:20:56.974501Z","steps":["trace[1210026985] 'agreement among raft nodes before linearized reading' (duration: 138.729325ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:56.97422Z","caller":"traceutil/trace.go:171","msg":"trace[1124413529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"139.211789ms","start":"2026-05-07T03:20:56.834996Z","end":"2026-05-07T03:20:56.974207Z","steps":["trace[1124413529] 'agreement among raft nodes before linearized reading' (duration: 139.038378ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:57.217047Z","caller":"traceutil/trace.go:171","msg":"trace[935575457] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"238.18621ms","start":"2026-05-07T03:20:56.978818Z","end":"2026-05-07T03:20:57.217004Z","steps":["trace[935575457] 'process raft request' (duration: 153.384485ms)","trace[935575457] 'compare' (duration: 84.105309ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:57.217745Z","caller":"traceutil/trace.go:171","msg":"trace[1113668696] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"238.905687ms","start":"2026-05-07T03:20:56.978818Z","end":"2026-05-07T03:20:57.217723Z","steps":["trace[1113668696] 'process raft request' (duration: 238.1301ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:57.218044Z","caller":"traceutil/trace.go:171","msg":"trace[1081990207] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"238.15037ms","start":"2026-05-07T03:20:56.979879Z","end":"2026-05-07T03:20:57.218029Z","steps":["trace[1081990207] 'process raft request' (duration: 237.736757ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:57.218465Z","caller":"traceutil/trace.go:171","msg":"trace[716218086] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"238.231191ms","start":"2026-05-07T03:20:56.980213Z","end":"2026-05-07T03:20:57.218444Z","steps":["trace[716218086] 'process raft request' (duration: 237.757947ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:57.218523Z","caller":"traceutil/trace.go:171","msg":"trace[1891665432] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"237.099892ms","start":"2026-05-07T03:20:56.981409Z","end":"2026-05-07T03:20:57.218509Z","steps":["trace[1891665432] 'process raft request' (duration: 236.88633ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:03.434958Z","caller":"traceutil/trace.go:171","msg":"trace[410318566] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"129.994682ms","start":"2026-05-07T03:21:03.304939Z","end":"2026-05-07T03:21:03.434934Z","steps":["trace[410318566] 'process raft request' (duration: 40.3172ms)","trace[410318566] 'compare' (duration: 89.522461ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:03.43559Z","caller":"traceutil/trace.go:171","msg":"trace[2076473164] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"126.949547ms","start":"2026-05-07T03:21:03.308626Z","end":"2026-05-07T03:21:03.435575Z","steps":["trace[2076473164] 'process raft request' (duration: 126.40348ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:03.435087Z","caller":"traceutil/trace.go:171","msg":"trace[144126667] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"127.968689ms","start":"2026-05-07T03:21:03.307104Z","end":"2026-05-07T03:21:03.435073Z","steps":["trace[144126667] 'process raft request' (duration: 127.793917ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:05.435731Z","caller":"traceutil/trace.go:171","msg":"trace[1465156149] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"128.13326ms","start":"2026-05-07T03:21:05.30757Z","end":"2026-05-07T03:21:05.435703Z","steps":["trace[1465156149] 'process raft request' (duration: 127.956018ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:11.589553Z","caller":"traceutil/trace.go:171","msg":"trace[811275305] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"106.4408ms","start":"2026-05-07T03:21:11.483087Z","end":"2026-05-07T03:21:11.589528Z","steps":["trace[811275305] 'read index received' (duration: 106.170786ms)","trace[811275305] 'applied index is now lower than readState.Index' (duration: 268.754µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:11.58961Z","caller":"traceutil/trace.go:171","msg":"trace[186968580] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"111.725466ms","start":"2026-05-07T03:21:11.477855Z","end":"2026-05-07T03:21:11.58958Z","steps":["trace[186968580] 'process raft request' (duration: 111.515523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:11.589873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.334445ms","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-05-07T03:21:11.589901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.785525ms","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-05-07T03:21:11.589871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.517692ms","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-05-07T03:21:11.589919Z","caller":"traceutil/trace.go:171","msg":"trace[1604171053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"105.459156ms","start":"2026-05-07T03:21:11.484445Z","end":"2026-05-07T03:21:11.589904Z","steps":["trace[1604171053] 'agreement among raft nodes before linearized reading' (duration: 105.337854ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:11.589968Z","caller":"traceutil/trace.go:171","msg":"trace[1199463366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"106.629263ms","start":"2026-05-07T03:21:11.483317Z","end":"2026-05-07T03:21:11.589946Z","steps":["trace[1199463366] 'agreement among raft nodes before linearized reading' (duration: 106.450431ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:11.589949Z","caller":"traceutil/trace.go:171","msg":"trace[512240399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"106.871706ms","start":"2026-05-07T03:21:11.483062Z","end":"2026-05-07T03:21:11.589934Z","steps":["trace[512240399] 'agreement among raft nodes before linearized reading' (duration: 106.601412ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:15.290752Z","caller":"traceutil/trace.go:171","msg":"trace[14114167] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"715.648126ms","start":"2026-05-07T03:21:14.575077Z","end":"2026-05-07T03:21:15.290725Z","steps":["trace[14114167] 'process raft request' (duration: 715.512814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.290898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:14.575059Z","time spent":"715.771628ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:21:15.302157Z","caller":"traceutil/trace.go:171","msg":"trace[26615115] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"725.921698ms","start":"2026-05-07T03:21:14.576211Z","end":"2026-05-07T03:21:15.302133Z","steps":["trace[26615115] 'process raft request' (duration: 725.640633ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:15.302241Z","caller":"traceutil/trace.go:171","msg":"trace[2042037151] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"717.790342ms","start":"2026-05-07T03:21:14.584434Z","end":"2026-05-07T03:21:15.302224Z","steps":["trace[2042037151] 'process raft request' (duration: 717.464197ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:15.302242Z","caller":"traceutil/trace.go:171","msg":"trace[1515840362] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"711.78172ms","start":"2026-05-07T03:21:14.590431Z","end":"2026-05-07T03:21:15.302212Z","steps":["trace[1515840362] 'process raft request' (duration: 711.509606ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:15.302238Z","caller":"traceutil/trace.go:171","msg":"trace[591764337] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"726.610949ms","start":"2026-05-07T03:21:14.575593Z","end":"2026-05-07T03:21:15.302204Z","steps":["trace[591764337] 'process raft request' (duration: 726.141391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.302329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:14.590413Z","time spent":"711.865621ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:21:15.302333Z","caller":"traceutil/trace.go:171","msg":"trace[1782975997] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"710.342308ms","start":"2026-05-07T03:21:14.591914Z","end":"2026-05-07T03:21:15.302256Z","steps":["trace[1782975997] 'process raft request' (duration: 710.058333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.302404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:14.575583Z","time spent":"726.749121ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:21:15.302427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:14.576203Z","time spent":"726.203432ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:21:15.302473Z","caller":"traceutil/trace.go:171","msg":"trace[1828186446] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"703.920759ms","start":"2026-05-07T03:21:14.598544Z","end":"2026-05-07T03:21:15.302465Z","steps":["trace[1828186446] 'process raft request' (duration: 703.525633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.30249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:14.591906Z","time spent":"710.51821ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:21:15.302509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:14.59853Z","time spent":"703.963919ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:21:15.302331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:14.584418Z","time spent":"717.855864ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:21:15.303053Z","caller":"traceutil/trace.go:171","msg":"trace[1658782428] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"704.438537ms","start":"2026-05-07T03:21:14.598601Z","end":"2026-05-07T03:21:15.303039Z","steps":["trace[1658782428] 'process raft request' (duration: 703.545183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.303106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:14.598596Z","time spent":"704.486168ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:21:15.303135Z","caller":"traceutil/trace.go:171","msg":"trace[1976466262] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"692.552234ms","start":"2026-05-07T03:21:14.610563Z","end":"2026-05-07T03:21:15.303115Z","steps":["trace[1976466262] 'process raft request' (duration: 692.389882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.303243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:14.610543Z","time spent":"692.645215ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:21:15.303291Z","caller":"traceutil/trace.go:171","msg":"trace[1805058792] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:539; }","duration":"293.479876ms","start":"2026-05-07T03:21:15.009799Z","end":"2026-05-07T03:21:15.303279Z","steps":["trace[1805058792] 'read index received' (duration: 280.869247ms)","trace[1805058792] 'applied index is now lower than readState.Index' (duration: 12.609769ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:21:15.303375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.571097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:21:15.303404Z","caller":"traceutil/trace.go:171","msg":"trace[181670873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"293.635389ms","start":"2026-05-07T03:21:15.009758Z","end":"2026-05-07T03:21:15.303393Z","steps":["trace[181670873] 'agreement among raft nodes before linearized reading' (duration: 293.562257ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:15.303491Z","caller":"traceutil/trace.go:171","msg":"trace[161739263] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"669.045742ms","start":"2026-05-07T03:21:14.634431Z","end":"2026-05-07T03:21:15.303477Z","steps":["trace[161739263] 'process raft request' (duration: 668.638916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.30356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:14.634411Z","time spent":"669.121574ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:21:15.3036Z","caller":"traceutil/trace.go:171","msg":"trace[2067814279] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"682.392088ms","start":"2026-05-07T03:21:14.621202Z","end":"2026-05-07T03:21:15.303595Z","steps":["trace[2067814279] 'process raft request' (duration: 681.827439ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:15.303515Z","caller":"traceutil/trace.go:171","msg":"trace[1441469178] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"563.471706ms","start":"2026-05-07T03:21:14.740036Z","end":"2026-05-07T03:21:15.303508Z","steps":["trace[1441469178] 'process raft request' (duration: 563.192312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.303643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:14.621181Z","time spent":"682.442939ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:21:15.303672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:14.739998Z","time spent":"563.65511ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:21:15.719617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.217854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:21:15.719742Z","caller":"traceutil/trace.go:171","msg":"trace[527424608] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"317.658968ms","start":"2026-05-07T03:21:15.402062Z","end":"2026-05-07T03:21:15.719721Z","steps":["trace[527424608] 'process raft request' (duration: 139.247791ms)","trace[527424608] 'compare' (duration: 178.076101ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:21:15.719803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:15.402044Z","time spent":"317.730829ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:21:15.72352Z","caller":"traceutil/trace.go:171","msg":"trace[2135155340] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"312.623558ms","start":"2026-05-07T03:21:15.410873Z","end":"2026-05-07T03:21:15.723497Z","steps":["trace[2135155340] 'process raft request' (duration: 312.403655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.723671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:15.410854Z","time spent":"312.739169ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:21:15.724257Z","caller":"traceutil/trace.go:171","msg":"trace[1359103485] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"311.901566ms","start":"2026-05-07T03:21:15.412324Z","end":"2026-05-07T03:21:15.724226Z","steps":["trace[1359103485] 'process raft request' (duration: 311.100484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.724461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:15.412318Z","time spent":"312.041188ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:21:15.724522Z","caller":"traceutil/trace.go:171","msg":"trace[848417451] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:572; }","duration":"121.914168ms","start":"2026-05-07T03:21:15.602592Z","end":"2026-05-07T03:21:15.724506Z","steps":["trace[848417451] 'read index received' (duration: 120.574607ms)","trace[848417451] 'applied index is now lower than readState.Index' (duration: 1.336811ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:15.724685Z","caller":"traceutil/trace.go:171","msg":"trace[998648969] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"309.676081ms","start":"2026-05-07T03:21:15.414999Z","end":"2026-05-07T03:21:15.724675Z","steps":["trace[998648969] 'process raft request' (duration: 309.093592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.724745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:15.414994Z","time spent":"309.718572ms","remote":"127.0.0.1:36636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:21:15.724873Z","caller":"traceutil/trace.go:171","msg":"trace[1117538861] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"307.135621ms","start":"2026-05-07T03:21:15.417728Z","end":"2026-05-07T03:21:15.724863Z","steps":["trace[1117538861] 'process raft request' (duration: 306.41663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.724929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:15.417722Z","time spent":"307.174232ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:21:15.725052Z","caller":"traceutil/trace.go:171","msg":"trace[1770206334] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"300.516297ms","start":"2026-05-07T03:21:15.424526Z","end":"2026-05-07T03:21:15.725042Z","steps":["trace[1770206334] 'process raft request' (duration: 299.722835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.725108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:15.424502Z","time spent":"300.575919ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:21:15.725219Z","caller":"traceutil/trace.go:171","msg":"trace[1601735706] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"292.551482ms","start":"2026-05-07T03:21:15.432659Z","end":"2026-05-07T03:21:15.725211Z","steps":["trace[1601735706] 'process raft request' (duration: 291.654288ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:15.725323Z","caller":"traceutil/trace.go:171","msg":"trace[1209765287] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"235.168779ms","start":"2026-05-07T03:21:15.490145Z","end":"2026-05-07T03:21:15.725314Z","steps":["trace[1209765287] 'process raft request' (duration: 234.311266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.725424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.839823ms","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-05-07T03:21:15.725465Z","caller":"traceutil/trace.go:171","msg":"trace[1558200435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"122.921324ms","start":"2026-05-07T03:21:15.602535Z","end":"2026-05-07T03:21:15.725457Z","steps":["trace[1558200435] 'agreement among raft nodes before linearized reading' (duration: 122.848842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:15.725676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.563199ms","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-05-07T03:21:15.725712Z","caller":"traceutil/trace.go:171","msg":"trace[1624245239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"103.65135ms","start":"2026-05-07T03:21:15.622053Z","end":"2026-05-07T03:21:15.725704Z","steps":["trace[1624245239] 'agreement among raft nodes before linearized reading' (duration: 103.538909ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:16.65277Z","caller":"traceutil/trace.go:171","msg":"trace[461062793] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"482.256534ms","start":"2026-05-07T03:21:16.170489Z","end":"2026-05-07T03:21:16.652746Z","steps":["trace[461062793] 'process raft request' (duration: 482.02598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:16.652952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:16.170471Z","time spent":"482.386657ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:21:16.790029Z","caller":"traceutil/trace.go:171","msg":"trace[1642550267] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"610.92158ms","start":"2026-05-07T03:21:16.179077Z","end":"2026-05-07T03:21:16.789999Z","steps":["trace[1642550267] 'process raft request' (duration: 609.996695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:16.790182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:16.179057Z","time spent":"611.057111ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:21:16.790787Z","caller":"traceutil/trace.go:171","msg":"trace[2057098879] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"535.189144ms","start":"2026-05-07T03:21:16.255574Z","end":"2026-05-07T03:21:16.790763Z","steps":["trace[2057098879] 'process raft request' (duration: 534.788307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:16.790917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:16.255542Z","time spent":"535.315775ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:21:16.794112Z","caller":"traceutil/trace.go:171","msg":"trace[1125231578] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"126.927648ms","start":"2026-05-07T03:21:16.667166Z","end":"2026-05-07T03:21:16.794094Z","steps":["trace[1125231578] 'process raft request' (duration: 126.836036ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:17.109069Z","caller":"traceutil/trace.go:171","msg":"trace[1738501024] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"170.761966ms","start":"2026-05-07T03:21:16.938277Z","end":"2026-05-07T03:21:17.109039Z","steps":["trace[1738501024] 'process raft request' (duration: 117.501614ms)","trace[1738501024] 'compare' (duration: 52.908417ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:17.112599Z","caller":"traceutil/trace.go:171","msg":"trace[2028171793] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:615; }","duration":"102.402447ms","start":"2026-05-07T03:21:17.010171Z","end":"2026-05-07T03:21:17.112573Z","steps":["trace[2028171793] 'read index received' (duration: 45.697809ms)","trace[2028171793] 'applied index is now lower than readState.Index' (duration: 56.703278ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:17.112719Z","caller":"traceutil/trace.go:171","msg":"trace[1663698845] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"112.814647ms","start":"2026-05-07T03:21:16.999884Z","end":"2026-05-07T03:21:17.112699Z","steps":["trace[1663698845] 'process raft request' (duration: 111.906772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:17.112757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:21:17.112813Z","caller":"traceutil/trace.go:171","msg":"trace[1811945196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"102.653961ms","start":"2026-05-07T03:21:17.010142Z","end":"2026-05-07T03:21:17.112796Z","steps":["trace[1811945196] 'agreement among raft nodes before linearized reading' (duration: 102.529399ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:17.558455Z","caller":"traceutil/trace.go:171","msg":"trace[1200298434] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"113.319126ms","start":"2026-05-07T03:21:17.44509Z","end":"2026-05-07T03:21:17.558409Z","steps":["trace[1200298434] 'process raft request' (duration: 81.562066ms)","trace[1200298434] 'compare' (duration: 31.423514ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:17.787787Z","caller":"traceutil/trace.go:171","msg":"trace[939409632] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"110.549331ms","start":"2026-05-07T03:21:17.677219Z","end":"2026-05-07T03:21:17.787768Z","steps":["trace[939409632] 'process raft request' (duration: 109.90153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:18.104353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.716008ms","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-05-07T03:21:18.104478Z","caller":"traceutil/trace.go:171","msg":"trace[1775821257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"355.871291ms","start":"2026-05-07T03:21:17.748579Z","end":"2026-05-07T03:21:18.104451Z","steps":["trace[1775821257] 'agreement among raft nodes before linearized reading' (duration: 39.478236ms)","trace[1775821257] 'range keys from in-memory index tree' (duration: 316.11945ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:21:18.104479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.881061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T03:21:18.104538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:17.748574Z","time spent":"355.950732ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T03:21:18.104555Z","caller":"traceutil/trace.go:171","msg":"trace[1333140460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"356.007833ms","start":"2026-05-07T03:21:17.748524Z","end":"2026-05-07T03:21:18.104532Z","steps":["trace[1333140460] 'agreement among raft nodes before linearized reading' (duration: 39.539597ms)","trace[1333140460] 'range keys from in-memory index tree' (duration: 316.282263ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:21:18.104562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.315148ms","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-05-07T03:21:18.104596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:17.748503Z","time spent":"356.085515ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T03:21:18.10463Z","caller":"traceutil/trace.go:171","msg":"trace[929825781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"356.398959ms","start":"2026-05-07T03:21:17.748205Z","end":"2026-05-07T03:21:18.104604Z","steps":["trace[929825781] 'agreement among raft nodes before linearized reading' (duration: 39.805112ms)","trace[929825781] 'range keys from in-memory index tree' (duration: 316.456785ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:21:18.104677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:17.748191Z","time spent":"356.474821ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T03:21:18.104746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.025143ms","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-05-07T03:21:18.104776Z","caller":"traceutil/trace.go:171","msg":"trace[606754971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"356.064804ms","start":"2026-05-07T03:21:17.748701Z","end":"2026-05-07T03:21:18.104765Z","steps":["trace[606754971] 'agreement among raft nodes before linearized reading' (duration: 39.348414ms)","trace[606754971] 'range keys from in-memory index tree' (duration: 316.646479ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:21:18.104802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:17.748693Z","time spent":"356.103594ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T03:21:18.104774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.014803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-05-07T03:21:18.104884Z","caller":"traceutil/trace.go:171","msg":"trace[532842810] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:599; }","duration":"403.161985ms","start":"2026-05-07T03:21:17.70169Z","end":"2026-05-07T03:21:18.104852Z","steps":["trace[532842810] 'agreement among raft nodes before linearized reading' (duration: 86.306273ms)","trace[532842810] 'range keys from in-memory index tree' (duration: 316.623598ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:21:18.104937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:17.701674Z","time spent":"403.252006ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":20889,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-05-07T03:21:22.020289Z","caller":"traceutil/trace.go:171","msg":"trace[651925887] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"208.230993ms","start":"2026-05-07T03:21:21.812016Z","end":"2026-05-07T03:21:22.020247Z","steps":["trace[651925887] 'process raft request' (duration: 116.698635ms)","trace[651925887] 'compare' (duration: 91.386725ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:22.601497Z","caller":"traceutil/trace.go:171","msg":"trace[902517610] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"149.443866ms","start":"2026-05-07T03:21:22.452029Z","end":"2026-05-07T03:21:22.601473Z","steps":["trace[902517610] 'process raft request' (duration: 148.724163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:23.261952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.23977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:21:23.262051Z","caller":"traceutil/trace.go:171","msg":"trace[1802828087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"252.395603ms","start":"2026-05-07T03:21:23.009634Z","end":"2026-05-07T03:21:23.26203Z","steps":["trace[1802828087] 'range keys from in-memory index tree' (duration: 252.154458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:23.262207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.474207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-btmvr\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-07T03:21:23.262333Z","caller":"traceutil/trace.go:171","msg":"trace[609340402] range","detail":"{range_begin:/registry/pods/kube-system/cilium-btmvr; range_end:; response_count:1; response_revision:628; }","duration":"223.66225ms","start":"2026-05-07T03:21:23.038645Z","end":"2026-05-07T03:21:23.262308Z","steps":["trace[609340402] 'range keys from in-memory index tree' (duration: 223.190401ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:23.447792Z","caller":"traceutil/trace.go:171","msg":"trace[1091995821] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"282.102004ms","start":"2026-05-07T03:21:23.165659Z","end":"2026-05-07T03:21:23.447761Z","steps":["trace[1091995821] 'process raft request' (duration: 281.496043ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:24.209204Z","caller":"traceutil/trace.go:171","msg":"trace[2066454449] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.002462294s","start":"2026-05-07T03:21:23.206721Z","end":"2026-05-07T03:21:24.209184Z","steps":["trace[2066454449] 'process raft request' (duration: 993.285684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:24.209363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:23.206685Z","time spent":"1.002601515s","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:21:24.209521Z","caller":"traceutil/trace.go:171","msg":"trace[1779309180] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"946.288451ms","start":"2026-05-07T03:21:23.263155Z","end":"2026-05-07T03:21:24.209444Z","steps":["trace[1779309180] 'read index received' (duration: 183.786565ms)","trace[1779309180] 'applied index is now lower than readState.Index' (duration: 762.500006ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:24.209651Z","caller":"traceutil/trace.go:171","msg":"trace[426369387] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"931.39349ms","start":"2026-05-07T03:21:23.278243Z","end":"2026-05-07T03:21:24.209637Z","steps":["trace[426369387] 'process raft request' (duration: 930.88291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:24.209781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:23.27822Z","time spent":"931.462621ms","remote":"127.0.0.1:36312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:21:24.209793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.611067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:21:24.209873Z","caller":"traceutil/trace.go:171","msg":"trace[913294000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"946.75095ms","start":"2026-05-07T03:21:23.263102Z","end":"2026-05-07T03:21:24.209852Z","steps":["trace[913294000] 'agreement among raft nodes before linearized reading' (duration: 946.576547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:24.209908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:23.263072Z","time spent":"946.829901ms","remote":"127.0.0.1:36116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:21:25.992811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.589471763s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:21:25.993765Z","caller":"traceutil/trace.go:171","msg":"trace[786700955] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"1.784138772s","start":"2026-05-07T03:21:24.209594Z","end":"2026-05-07T03:21:25.993733Z","steps":["trace[786700955] 'read index received' (duration: 193.708291ms)","trace[786700955] 'applied index is now lower than readState.Index' (duration: 1.590425831s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:21:25.993973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.798342895s","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-05-07T03:21:25.994008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.808825059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-07T03:21:25.993997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.802576274s","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-05-07T03:21:25.994046Z","caller":"traceutil/trace.go:171","msg":"trace[911445851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"1.798445897s","start":"2026-05-07T03:21:24.195584Z","end":"2026-05-07T03:21:25.99403Z","steps":["trace[911445851] 'agreement among raft nodes before linearized reading' (duration: 1.798191972s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:25.994096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:24.195574Z","time spent":"1.798497438s","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T03:21:25.994069Z","caller":"traceutil/trace.go:171","msg":"trace[260694644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"1.80892038s","start":"2026-05-07T03:21:24.185131Z","end":"2026-05-07T03:21:25.994051Z","steps":["trace[260694644] 'agreement among raft nodes before linearized reading' (duration: 1.808740037s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:25.994119Z","caller":"traceutil/trace.go:171","msg":"trace[526307196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"1.802713205s","start":"2026-05-07T03:21:24.19135Z","end":"2026-05-07T03:21:25.994063Z","steps":["trace[526307196] 'agreement among raft nodes before linearized reading' (duration: 1.80244816s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:25.994149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:24.185111Z","time spent":"1.809025723s","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T03:21:25.99417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:24.191321Z","time spent":"1.802840988s","remote":"127.0.0.1:36378","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-05-07T03:21:25.994229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.832313182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T03:21:25.994281Z","caller":"traceutil/trace.go:171","msg":"trace[737391783] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"2.479911225s","start":"2026-05-07T03:21:23.514341Z","end":"2026-05-07T03:21:25.994252Z","steps":["trace[737391783] 'process raft request' (duration: 888.915843ms)","trace[737391783] 'compare' (duration: 1.58875179s)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:25.994292Z","caller":"traceutil/trace.go:171","msg":"trace[1962722602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"1.832617628s","start":"2026-05-07T03:21:24.161661Z","end":"2026-05-07T03:21:25.994278Z","steps":["trace[1962722602] 'agreement among raft nodes before linearized reading' (duration: 1.832229681s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:25.994362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:23.514307Z","time spent":"2.480001656s","remote":"127.0.0.1:36624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:21:25.994372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.775799449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:21:25.99442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.748724408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-07T03:21:25.99441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:24.161641Z","time spent":"1.83273018s","remote":"127.0.0.1:36378","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-05-07T03:21:25.994469Z","caller":"traceutil/trace.go:171","msg":"trace[1796775860] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:632; }","duration":"1.748770029s","start":"2026-05-07T03:21:24.245683Z","end":"2026-05-07T03:21:25.994453Z","steps":["trace[1796775860] 'agreement among raft nodes before linearized reading' (duration: 1.748641946s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:25.994525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:24.245647Z","time spent":"1.748867191s","remote":"127.0.0.1:36128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.140\" "} {"level":"info","ts":"2026-05-07T03:21:25.994469Z","caller":"traceutil/trace.go:171","msg":"trace[1309620741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"1.775922461s","start":"2026-05-07T03:21:24.218519Z","end":"2026-05-07T03:21:25.994441Z","steps":["trace[1309620741] 'agreement among raft nodes before linearized reading' (duration: 1.775783658s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:25.994569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:24.218506Z","time spent":"1.776056053s","remote":"127.0.0.1:36110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:21:27.786988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.440603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:21:27.787003Z","caller":"traceutil/trace.go:171","msg":"trace[583555512] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"108.479616ms","start":"2026-05-07T03:21:27.678492Z","end":"2026-05-07T03:21:27.786972Z","steps":["trace[583555512] 'process raft request' (duration: 107.382325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:27.78706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.568845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:21:27.787135Z","caller":"traceutil/trace.go:171","msg":"trace[1419148216] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"107.402525ms","start":"2026-05-07T03:21:27.679719Z","end":"2026-05-07T03:21:27.787122Z","steps":["trace[1419148216] 'process raft request' (duration: 107.095629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:27.786986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.497653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:21:27.787288Z","caller":"traceutil/trace.go:171","msg":"trace[961054389] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:656; }","duration":"112.843571ms","start":"2026-05-07T03:21:27.674429Z","end":"2026-05-07T03:21:27.787272Z","steps":["trace[961054389] 'agreement among raft nodes before linearized reading' (duration: 112.455873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:27.787305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.82726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:21:27.78708Z","caller":"traceutil/trace.go:171","msg":"trace[1627980115] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:656; }","duration":"112.579305ms","start":"2026-05-07T03:21:27.674477Z","end":"2026-05-07T03:21:27.787057Z","steps":["trace[1627980115] 'agreement among raft nodes before linearized reading' (duration: 112.449243ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:27.787174Z","caller":"traceutil/trace.go:171","msg":"trace[2004202334] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:656; }","duration":"112.665927ms","start":"2026-05-07T03:21:27.674483Z","end":"2026-05-07T03:21:27.787149Z","steps":["trace[2004202334] 'agreement among raft nodes before linearized reading' (duration: 112.553575ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:27.787401Z","caller":"traceutil/trace.go:171","msg":"trace[321901724] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:656; }","duration":"112.938842ms","start":"2026-05-07T03:21:27.674446Z","end":"2026-05-07T03:21:27.787385Z","steps":["trace[321901724] 'agreement among raft nodes before linearized reading' (duration: 112.820369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:27.787592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.79017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:21:27.787632Z","caller":"traceutil/trace.go:171","msg":"trace[1041331377] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:656; }","duration":"110.862892ms","start":"2026-05-07T03:21:27.676758Z","end":"2026-05-07T03:21:27.787621Z","steps":["trace[1041331377] 'agreement among raft nodes before linearized reading' (duration: 110.776971ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:27.924143Z","caller":"traceutil/trace.go:171","msg":"trace[1098083280] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"130.253826ms","start":"2026-05-07T03:21:27.793857Z","end":"2026-05-07T03:21:27.924111Z","steps":["trace[1098083280] 'process raft request' (duration: 104.631381ms)","trace[1098083280] 'compare' (duration: 25.435561ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:27.924379Z","caller":"traceutil/trace.go:171","msg":"trace[975934243] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"129.765777ms","start":"2026-05-07T03:21:27.794601Z","end":"2026-05-07T03:21:27.924367Z","steps":["trace[975934243] 'process raft request' (duration: 129.460441ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:28.172978Z","caller":"traceutil/trace.go:171","msg":"trace[1584570359] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:690; }","duration":"103.60132ms","start":"2026-05-07T03:21:28.069347Z","end":"2026-05-07T03:21:28.172948Z","steps":["trace[1584570359] 'read index received' (duration: 74.894858ms)","trace[1584570359] 'applied index is now lower than readState.Index' (duration: 28.704552ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:21:28.173233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.804204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:21:28.173327Z","caller":"traceutil/trace.go:171","msg":"trace[1043045395] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:664; }","duration":"103.962057ms","start":"2026-05-07T03:21:28.069342Z","end":"2026-05-07T03:21:28.173304Z","steps":["trace[1043045395] 'agreement among raft nodes before linearized reading' (duration: 103.763253ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:28.173119Z","caller":"traceutil/trace.go:171","msg":"trace[1780699680] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"106.354474ms","start":"2026-05-07T03:21:28.066735Z","end":"2026-05-07T03:21:28.17309Z","steps":["trace[1780699680] 'process raft request' (duration: 98.575771ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:28.357914Z","caller":"traceutil/trace.go:171","msg":"trace[658847599] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"177.923476ms","start":"2026-05-07T03:21:28.179965Z","end":"2026-05-07T03:21:28.357889Z","steps":["trace[658847599] 'process raft request' (duration: 177.588049ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:28.35831Z","caller":"traceutil/trace.go:171","msg":"trace[1870864037] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"169.705125ms","start":"2026-05-07T03:21:28.188535Z","end":"2026-05-07T03:21:28.35824Z","steps":["trace[1870864037] 'process raft request' (duration: 169.295397ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:28.522473Z","caller":"traceutil/trace.go:171","msg":"trace[2030183746] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"155.6232ms","start":"2026-05-07T03:21:28.366818Z","end":"2026-05-07T03:21:28.522441Z","steps":["trace[2030183746] 'process raft request' (duration: 109.625418ms)","trace[2030183746] 'compare' (duration: 45.825018ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:28.5545Z","caller":"traceutil/trace.go:171","msg":"trace[848239267] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"178.60309ms","start":"2026-05-07T03:21:28.375866Z","end":"2026-05-07T03:21:28.554469Z","steps":["trace[848239267] 'process raft request' (duration: 178.330664ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:28.754064Z","caller":"traceutil/trace.go:171","msg":"trace[1935110699] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"143.757967ms","start":"2026-05-07T03:21:28.610272Z","end":"2026-05-07T03:21:28.75403Z","steps":["trace[1935110699] 'process raft request' (duration: 143.543412ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:28.810972Z","caller":"traceutil/trace.go:171","msg":"trace[333116862] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"137.420683ms","start":"2026-05-07T03:21:28.673523Z","end":"2026-05-07T03:21:28.810943Z","steps":["trace[333116862] 'process raft request' (duration: 137.27226ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:30.143952Z","caller":"traceutil/trace.go:171","msg":"trace[1878358075] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"118.996114ms","start":"2026-05-07T03:21:30.024926Z","end":"2026-05-07T03:21:30.143922Z","steps":["trace[1878358075] 'process raft request' (duration: 114.3332ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:30.611729Z","caller":"traceutil/trace.go:171","msg":"trace[1152075198] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"113.152906ms","start":"2026-05-07T03:21:30.498549Z","end":"2026-05-07T03:21:30.611702Z","steps":["trace[1152075198] 'process raft request' (duration: 112.987933ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:30.797436Z","caller":"traceutil/trace.go:171","msg":"trace[857840844] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"114.112004ms","start":"2026-05-07T03:21:30.683275Z","end":"2026-05-07T03:21:30.797387Z","steps":["trace[857840844] 'process raft request' (duration: 113.856799ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:31.22114Z","caller":"traceutil/trace.go:171","msg":"trace[1571457977] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"115.753538ms","start":"2026-05-07T03:21:31.10533Z","end":"2026-05-07T03:21:31.221084Z","steps":["trace[1571457977] 'process raft request' (duration: 100.287423ms)","trace[1571457977] 'compare' (duration: 15.317702ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:31.743622Z","caller":"traceutil/trace.go:171","msg":"trace[1489196326] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"113.85046ms","start":"2026-05-07T03:21:31.629737Z","end":"2026-05-07T03:21:31.743588Z","steps":["trace[1489196326] 'process raft request' (duration: 113.674286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:32.210034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.565692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:21:32.210173Z","caller":"traceutil/trace.go:171","msg":"trace[701670979] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"339.542978ms","start":"2026-05-07T03:21:31.87061Z","end":"2026-05-07T03:21:32.210153Z","steps":["trace[701670979] 'read index received' (duration: 48.251µs)","trace[701670979] 'applied index is now lower than readState.Index' (duration: 339.493317ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:32.210358Z","caller":"traceutil/trace.go:171","msg":"trace[1208899219] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"398.733365ms","start":"2026-05-07T03:21:31.811594Z","end":"2026-05-07T03:21:32.210327Z","steps":["trace[1208899219] 'process raft request' (duration: 24.800795ms)","trace[1208899219] 'compare' (duration: 373.283356ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:21:32.210487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:31.811569Z","time spent":"398.859727ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:21:32.21062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.624808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:21:32.210663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.037939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-05-07T03:21:32.21069Z","caller":"traceutil/trace.go:171","msg":"trace[610783282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"200.73372ms","start":"2026-05-07T03:21:32.009937Z","end":"2026-05-07T03:21:32.210671Z","steps":["trace[610783282] 'agreement among raft nodes before linearized reading' (duration: 200.584887ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:32.210704Z","caller":"traceutil/trace.go:171","msg":"trace[348020426] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:719; }","duration":"340.12374ms","start":"2026-05-07T03:21:31.870568Z","end":"2026-05-07T03:21:32.210692Z","steps":["trace[348020426] 'agreement among raft nodes before linearized reading' (duration: 339.850835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:32.210777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:31.870548Z","time spent":"340.216953ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:21:32.210863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.314514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-btmvr\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-05-07T03:21:32.210911Z","caller":"traceutil/trace.go:171","msg":"trace[2132384701] range","detail":"{range_begin:/registry/pods/kube-system/cilium-btmvr; range_end:; response_count:1; response_revision:719; }","duration":"108.361235ms","start":"2026-05-07T03:21:32.102535Z","end":"2026-05-07T03:21:32.210897Z","steps":["trace[2132384701] 'agreement among raft nodes before linearized reading' (duration: 108.203711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:32.210972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.712892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-05-07T03:21:32.211051Z","caller":"traceutil/trace.go:171","msg":"trace[576272638] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:719; }","duration":"140.847564ms","start":"2026-05-07T03:21:32.070185Z","end":"2026-05-07T03:21:32.211033Z","steps":["trace[576272638] 'agreement among raft nodes before linearized reading' (duration: 140.557618ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:32.607071Z","caller":"traceutil/trace.go:171","msg":"trace[555373177] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"383.590431ms","start":"2026-05-07T03:21:32.223459Z","end":"2026-05-07T03:21:32.60705Z","steps":["trace[555373177] 'process raft request' (duration: 380.235012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:32.607878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:32.223439Z","time spent":"384.085862ms","remote":"127.0.0.1:36312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:21:32.651201Z","caller":"traceutil/trace.go:171","msg":"trace[820143935] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:748; }","duration":"382.107651ms","start":"2026-05-07T03:21:32.26904Z","end":"2026-05-07T03:21:32.651148Z","steps":["trace[820143935] 'read index received' (duration: 334.699353ms)","trace[820143935] 'applied index is now lower than readState.Index' (duration: 47.407328ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:32.651264Z","caller":"traceutil/trace.go:171","msg":"trace[1245517540] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"426.153349ms","start":"2026-05-07T03:21:32.225065Z","end":"2026-05-07T03:21:32.651218Z","steps":["trace[1245517540] 'process raft request' (duration: 425.893104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:32.651479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.423847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T03:21:32.651565Z","caller":"traceutil/trace.go:171","msg":"trace[996633519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:721; }","duration":"382.53132ms","start":"2026-05-07T03:21:32.269007Z","end":"2026-05-07T03:21:32.651539Z","steps":["trace[996633519] 'agreement among raft nodes before linearized reading' (duration: 382.316045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:32.651565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:32.225056Z","time spent":"426.362923ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:21:32.651623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:32.268993Z","time spent":"382.620781ms","remote":"127.0.0.1:36378","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-05-07T03:21:32.651723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.500099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-05-07T03:21:32.651799Z","caller":"traceutil/trace.go:171","msg":"trace[1864883869] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:721; }","duration":"382.616341ms","start":"2026-05-07T03:21:32.269163Z","end":"2026-05-07T03:21:32.65178Z","steps":["trace[1864883869] 'agreement among raft nodes before linearized reading' (duration: 382.308084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:32.651838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:32.269146Z","time spent":"382.682613ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:21:32.651847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.110369ms","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-05-07T03:21:32.651754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.666014ms","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-05-07T03:21:32.651971Z","caller":"traceutil/trace.go:171","msg":"trace[601154633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:721; }","duration":"272.911239ms","start":"2026-05-07T03:21:32.37903Z","end":"2026-05-07T03:21:32.651942Z","steps":["trace[601154633] 'agreement among raft nodes before linearized reading' (duration: 272.438479ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:32.651894Z","caller":"traceutil/trace.go:171","msg":"trace[342392164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:721; }","duration":"379.16957ms","start":"2026-05-07T03:21:32.27271Z","end":"2026-05-07T03:21:32.651879Z","steps":["trace[342392164] 'agreement among raft nodes before linearized reading' (duration: 379.055647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:32.652138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:32.272703Z","time spent":"379.422395ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T03:21:34.186441Z","caller":"traceutil/trace.go:171","msg":"trace[500382868] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"109.777228ms","start":"2026-05-07T03:21:34.076633Z","end":"2026-05-07T03:21:34.18641Z","steps":["trace[500382868] 'process raft request' (duration: 98.285366ms)","trace[500382868] 'compare' (duration: 11.202926ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:34.42509Z","caller":"traceutil/trace.go:171","msg":"trace[2017812523] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"178.422779ms","start":"2026-05-07T03:21:34.24664Z","end":"2026-05-07T03:21:34.425063Z","steps":["trace[2017812523] 'read index received' (duration: 152.232385ms)","trace[2017812523] 'applied index is now lower than readState.Index' (duration: 26.188964ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:34.425142Z","caller":"traceutil/trace.go:171","msg":"trace[656756999] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"224.066453ms","start":"2026-05-07T03:21:34.20104Z","end":"2026-05-07T03:21:34.425106Z","steps":["trace[656756999] 'process raft request' (duration: 197.951601ms)","trace[656756999] 'compare' (duration: 25.939748ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:21:34.425298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.652173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T03:21:34.425332Z","caller":"traceutil/trace.go:171","msg":"trace[1393171596] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:734; }","duration":"178.770616ms","start":"2026-05-07T03:21:34.246551Z","end":"2026-05-07T03:21:34.425322Z","steps":["trace[1393171596] 'agreement among raft nodes before linearized reading' (duration: 178.610773ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:34.449282Z","caller":"traceutil/trace.go:171","msg":"trace[335171055] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"145.627006ms","start":"2026-05-07T03:21:34.303608Z","end":"2026-05-07T03:21:34.449235Z","steps":["trace[335171055] 'process raft request' (duration: 145.387201ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:34.828521Z","caller":"traceutil/trace.go:171","msg":"trace[758237733] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"151.553981ms","start":"2026-05-07T03:21:34.676767Z","end":"2026-05-07T03:21:34.828321Z","steps":["trace[758237733] 'process raft request' (duration: 123.57981ms)","trace[758237733] 'compare' (duration: 27.861429ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:34.862999Z","caller":"traceutil/trace.go:171","msg":"trace[2138191155] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"160.155133ms","start":"2026-05-07T03:21:34.702824Z","end":"2026-05-07T03:21:34.862979Z","steps":["trace[2138191155] 'process raft request' (duration: 160.050981ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:41.144904Z","caller":"traceutil/trace.go:171","msg":"trace[527100091] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"263.005961ms","start":"2026-05-07T03:21:40.881875Z","end":"2026-05-07T03:21:41.144881Z","steps":["trace[527100091] 'read index received' (duration: 262.875758ms)","trace[527100091] 'applied index is now lower than readState.Index' (duration: 129.023µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:41.145012Z","caller":"traceutil/trace.go:171","msg":"trace[1872459596] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"290.325776ms","start":"2026-05-07T03:21:40.854652Z","end":"2026-05-07T03:21:41.144978Z","steps":["trace[1872459596] 'process raft request' (duration: 290.06431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:41.145094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.200286ms","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-05-07T03:21:41.145145Z","caller":"traceutil/trace.go:171","msg":"trace[734467289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"263.284617ms","start":"2026-05-07T03:21:40.881846Z","end":"2026-05-07T03:21:41.145131Z","steps":["trace[734467289] 'agreement among raft nodes before linearized reading' (duration: 263.129024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:43.659843Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605641898039710,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:21:44.160191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605641898039710,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:21:44.314969Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.157072515s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:21:44.315592Z","caller":"traceutil/trace.go:171","msg":"trace[21477401] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"1.157770391s","start":"2026-05-07T03:21:43.157796Z","end":"2026-05-07T03:21:44.315566Z","steps":["trace[21477401] 'process raft request' (duration: 1.157554886s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:44.315791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:43.157774Z","time spent":"1.157910794s","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:21:44.513674Z","caller":"traceutil/trace.go:171","msg":"trace[36037384] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"1.354215092s","start":"2026-05-07T03:21:43.159415Z","end":"2026-05-07T03:21:44.51363Z","steps":["trace[36037384] 'read index received' (duration: 1.15640504s)","trace[36037384] 'applied index is now lower than readState.Index' (duration: 197.808742ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:44.513712Z","caller":"traceutil/trace.go:171","msg":"trace[256624115] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.355482241s","start":"2026-05-07T03:21:43.158195Z","end":"2026-05-07T03:21:44.513677Z","steps":["trace[256624115] 'process raft request' (duration: 1.332139456s)","trace[256624115] 'compare' (duration: 23.146431ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:21:44.513945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.173028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-07T03:21:44.513966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.729699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:21:44.514004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.74614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-07T03:21:44.514326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:43.158177Z","time spent":"1.355745907s","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:21:44.51412Z","caller":"traceutil/trace.go:171","msg":"trace[1277586613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"502.899663ms","start":"2026-05-07T03:21:44.011175Z","end":"2026-05-07T03:21:44.514074Z","steps":["trace[1277586613] 'agreement among raft nodes before linearized reading' (duration: 502.737689ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:44.51473Z","caller":"traceutil/trace.go:171","msg":"trace[887665371] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:802; }","duration":"357.945175ms","start":"2026-05-07T03:21:44.156196Z","end":"2026-05-07T03:21:44.514141Z","steps":["trace[887665371] 'agreement among raft nodes before linearized reading' (duration: 357.650288ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:44.514014Z","caller":"traceutil/trace.go:171","msg":"trace[327746706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"657.309071ms","start":"2026-05-07T03:21:43.856694Z","end":"2026-05-07T03:21:44.514003Z","steps":["trace[327746706] 'agreement among raft nodes before linearized reading' (duration: 657.110676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:44.514814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:43.856675Z","time spent":"658.122691ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T03:21:44.514815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:44.156168Z","time spent":"358.6077ms","remote":"127.0.0.1:36440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:21:44.514825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355367799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lwnt4\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-07T03:21:44.514905Z","caller":"traceutil/trace.go:171","msg":"trace[1412314342] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lwnt4; range_end:; response_count:1; response_revision:802; }","duration":"1.355484792s","start":"2026-05-07T03:21:43.159397Z","end":"2026-05-07T03:21:44.514882Z","steps":["trace[1412314342] 'agreement among raft nodes before linearized reading' (duration: 1.354383336s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:44.514959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:43.159391Z","time spent":"1.355553613s","remote":"127.0.0.1:36312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lwnt4\" "} {"level":"warn","ts":"2026-05-07T03:21:44.514968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.575893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T03:21:44.51505Z","caller":"traceutil/trace.go:171","msg":"trace[1411244487] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:802; }","duration":"267.690066ms","start":"2026-05-07T03:21:44.247338Z","end":"2026-05-07T03:21:44.515028Z","steps":["trace[1411244487] 'agreement among raft nodes before linearized reading' (duration: 267.470181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:44.51477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:21:44.011155Z","time spent":"503.60454ms","remote":"127.0.0.1:36110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:21:52.243239Z","caller":"traceutil/trace.go:171","msg":"trace[155534778] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"167.509066ms","start":"2026-05-07T03:21:52.075709Z","end":"2026-05-07T03:21:52.243218Z","steps":["trace[155534778] 'process raft request' (duration: 167.359283ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:52.496375Z","caller":"traceutil/trace.go:171","msg":"trace[290838778] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"107.034189ms","start":"2026-05-07T03:21:52.389318Z","end":"2026-05-07T03:21:52.496352Z","steps":["trace[290838778] 'process raft request' (duration: 106.392083ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:52.671659Z","caller":"traceutil/trace.go:171","msg":"trace[1215943640] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"110.733691ms","start":"2026-05-07T03:21:52.560888Z","end":"2026-05-07T03:21:52.671621Z","steps":["trace[1215943640] 'process raft request' (duration: 54.430907ms)","trace[1215943640] 'compare' (duration: 56.046287ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:52.893393Z","caller":"traceutil/trace.go:171","msg":"trace[643548647] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"115.044078ms","start":"2026-05-07T03:21:52.778326Z","end":"2026-05-07T03:21:52.893371Z","steps":["trace[643548647] 'read index received' (duration: 114.911685ms)","trace[643548647] 'applied index is now lower than readState.Index' (duration: 131.283µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:52.893507Z","caller":"traceutil/trace.go:171","msg":"trace[1532433826] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"124.046763ms","start":"2026-05-07T03:21:52.769429Z","end":"2026-05-07T03:21:52.893476Z","steps":["trace[1532433826] 'process raft request' (duration: 123.791976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:52.893582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.232973ms","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-05-07T03:21:52.893672Z","caller":"traceutil/trace.go:171","msg":"trace[1719622887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:886; }","duration":"115.362726ms","start":"2026-05-07T03:21:52.778299Z","end":"2026-05-07T03:21:52.893662Z","steps":["trace[1719622887] 'agreement among raft nodes before linearized reading' (duration: 115.164301ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:53.398607Z","caller":"traceutil/trace.go:171","msg":"trace[26440161] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"116.139977ms","start":"2026-05-07T03:21:53.28244Z","end":"2026-05-07T03:21:53.39858Z","steps":["trace[26440161] 'process raft request' (duration: 115.991713ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:53.895909Z","caller":"traceutil/trace.go:171","msg":"trace[840212063] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"163.024314ms","start":"2026-05-07T03:21:53.732854Z","end":"2026-05-07T03:21:53.895879Z","steps":["trace[840212063] 'process raft request' (duration: 162.847489ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:54.394541Z","caller":"traceutil/trace.go:171","msg":"trace[1798604208] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"147.099571ms","start":"2026-05-07T03:21:54.247417Z","end":"2026-05-07T03:21:54.394517Z","steps":["trace[1798604208] 'read index received' (duration: 146.943147ms)","trace[1798604208] 'applied index is now lower than readState.Index' (duration: 155.184µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:21:54.39461Z","caller":"traceutil/trace.go:171","msg":"trace[1865480194] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"182.458195ms","start":"2026-05-07T03:21:54.212126Z","end":"2026-05-07T03:21:54.394584Z","steps":["trace[1865480194] 'process raft request' (duration: 182.228639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:54.394856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.420648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T03:21:54.39496Z","caller":"traceutil/trace.go:171","msg":"trace[24509807] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:895; }","duration":"147.559892ms","start":"2026-05-07T03:21:54.247371Z","end":"2026-05-07T03:21:54.394931Z","steps":["trace[24509807] 'agreement among raft nodes before linearized reading' (duration: 147.272705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:59.725553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.006146ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-07T03:22:26.842889Z","caller":"traceutil/trace.go:171","msg":"trace[1210354112] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"110.012162ms","start":"2026-05-07T03:22:26.732856Z","end":"2026-05-07T03:22:26.842868Z","steps":["trace[1210354112] 'process raft request' (duration: 109.891029ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:31.049998Z","caller":"traceutil/trace.go:171","msg":"trace[1665336646] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"189.432299ms","start":"2026-05-07T03:22:30.860541Z","end":"2026-05-07T03:22:31.049973Z","steps":["trace[1665336646] 'process raft request' (duration: 189.279414ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:31.050167Z","caller":"traceutil/trace.go:171","msg":"trace[50050233] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1130; }","duration":"114.085276ms","start":"2026-05-07T03:22:30.936058Z","end":"2026-05-07T03:22:31.050143Z","steps":["trace[50050233] 'read index received' (duration: 114.073686ms)","trace[50050233] 'applied index is now lower than readState.Index' (duration: 9.87µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:22:31.050434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.9232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-07T03:22:31.050419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.343964ms","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-05-07T03:22:31.050472Z","caller":"traceutil/trace.go:171","msg":"trace[1193084067] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1087; }","duration":"106.018963ms","start":"2026-05-07T03:22:30.944444Z","end":"2026-05-07T03:22:31.050463Z","steps":["trace[1193084067] 'agreement among raft nodes before linearized reading' (duration: 105.844188ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:31.050492Z","caller":"traceutil/trace.go:171","msg":"trace[318832323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1087; }","duration":"114.458377ms","start":"2026-05-07T03:22:30.936016Z","end":"2026-05-07T03:22:31.050475Z","steps":["trace[318832323] 'agreement among raft nodes before linearized reading' (duration: 114.22089ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:35.788735Z","caller":"traceutil/trace.go:171","msg":"trace[144489046] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"147.10804ms","start":"2026-05-07T03:22:35.641598Z","end":"2026-05-07T03:22:35.788706Z","steps":["trace[144489046] 'process raft request' (duration: 146.922474ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:03.707145Z","caller":"traceutil/trace.go:171","msg":"trace[1599185756] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"122.602377ms","start":"2026-05-07T03:23:03.584512Z","end":"2026-05-07T03:23:03.707114Z","steps":["trace[1599185756] 'process raft request' (duration: 42.840123ms)","trace[1599185756] 'compare' (duration: 79.502095ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:23:03.707449Z","caller":"traceutil/trace.go:171","msg":"trace[305415937] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"121.620535ms","start":"2026-05-07T03:23:03.585351Z","end":"2026-05-07T03:23:03.706972Z","steps":["trace[305415937] 'process raft request' (duration: 121.585254ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:04.386797Z","caller":"traceutil/trace.go:171","msg":"trace[2109453430] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"108.255613ms","start":"2026-05-07T03:23:04.278519Z","end":"2026-05-07T03:23:04.386775Z","steps":["trace[2109453430] 'process raft request' (duration: 53.342041ms)","trace[2109453430] 'compare' (duration: 54.794949ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:23:07.375391Z","caller":"traceutil/trace.go:171","msg":"trace[1774884244] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"305.394887ms","start":"2026-05-07T03:23:07.069979Z","end":"2026-05-07T03:23:07.375374Z","steps":["trace[1774884244] 'process raft request' (duration: 305.285734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:07.375503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:07.069959Z","time spent":"305.48668ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:23:07.58885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.72394ms","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-05-07T03:23:07.588909Z","caller":"traceutil/trace.go:171","msg":"trace[2022667595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1205; }","duration":"129.821044ms","start":"2026-05-07T03:23:07.459076Z","end":"2026-05-07T03:23:07.588897Z","steps":["trace[2022667595] 'range keys from in-memory index tree' (duration: 129.596367ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:32.415916Z","caller":"traceutil/trace.go:171","msg":"trace[1966469958] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"155.077217ms","start":"2026-05-07T03:23:32.260811Z","end":"2026-05-07T03:23:32.415888Z","steps":["trace[1966469958] 'process raft request' (duration: 154.693154ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:23:40.334285Z","caller":"traceutil/trace.go:171","msg":"trace[1837285599] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1405; }","duration":"503.159905ms","start":"2026-05-07T03:23:39.831095Z","end":"2026-05-07T03:23:40.334255Z","steps":["trace[1837285599] 'read index received' (duration: 460.91703ms)","trace[1837285599] 'applied index is now lower than readState.Index' (duration: 42.239615ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:23:40.334543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.324471ms","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-05-07T03:23:40.334582Z","caller":"traceutil/trace.go:171","msg":"trace[1583024423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1348; }","duration":"501.401544ms","start":"2026-05-07T03:23:39.833171Z","end":"2026-05-07T03:23:40.334573Z","steps":["trace[1583024423] 'agreement among raft nodes before linearized reading' (duration: 501.242178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:40.334586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.639263ms","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-05-07T03:23:40.334684Z","caller":"traceutil/trace.go:171","msg":"trace[1208576929] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1348; }","duration":"284.785049ms","start":"2026-05-07T03:23:40.049881Z","end":"2026-05-07T03:23:40.334666Z","steps":["trace[1208576929] 'agreement among raft nodes before linearized reading' (duration: 284.545291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:40.334622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:39.83316Z","time spent":"501.451065ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T03:23:40.334794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.647041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-07T03:23:40.334564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.489351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:23:40.334901Z","caller":"traceutil/trace.go:171","msg":"trace[1723018250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1348; }","duration":"325.891635ms","start":"2026-05-07T03:23:40.008998Z","end":"2026-05-07T03:23:40.33489Z","steps":["trace[1723018250] 'agreement among raft nodes before linearized reading' (duration: 325.46715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:40.334919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.058252ms","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-05-07T03:23:40.334975Z","caller":"traceutil/trace.go:171","msg":"trace[1325280038] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1348; }","duration":"501.125914ms","start":"2026-05-07T03:23:39.833815Z","end":"2026-05-07T03:23:40.334941Z","steps":["trace[1325280038] 'agreement among raft nodes before linearized reading' (duration: 501.044112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:40.334979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:40.008984Z","time spent":"325.950737ms","remote":"127.0.0.1:36110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:23:40.335003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:39.833792Z","time spent":"501.205347ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T03:23:40.335112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.717074ms","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-05-07T03:23:40.335186Z","caller":"traceutil/trace.go:171","msg":"trace[580088308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1348; }","duration":"315.827438ms","start":"2026-05-07T03:23:40.01934Z","end":"2026-05-07T03:23:40.335168Z","steps":["trace[580088308] 'agreement among raft nodes before linearized reading' (duration: 315.486336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:40.335262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:40.019325Z","time spent":"315.912741ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T03:23:40.334926Z","caller":"traceutil/trace.go:171","msg":"trace[959264321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1348; }","duration":"503.803106ms","start":"2026-05-07T03:23:39.831049Z","end":"2026-05-07T03:23:40.334852Z","steps":["trace[959264321] 'agreement among raft nodes before linearized reading' (duration: 503.31826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:23:40.335375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:23:39.831029Z","time spent":"504.333325ms","remote":"127.0.0.1:36378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}