{"level":"warn","ts":"2026-05-14T05:11:59.571796Z","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-14T05:11:59.571944Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-14T05:11:59.572048Z","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-14T05:11:59.57207Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-05-14T05:11:59.572098Z","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-14T05:11:59.573335Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-05-14T05:11:59.573469Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-14T05:11:59.577468Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.636078ms"} {"level":"info","ts":"2026-05-14T05:11:59.587402Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-05-14T05:11:59.587512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T05:11:59.587541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-05-14T05:11:59.587564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T05:11:59.587578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-05-14T05:11:59.587641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-05-14T05:11:59.594204Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T05:11:59.595622Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T05:11:59.598402Z","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-14T05:11:59.60044Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T05:11:59.605055Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T05:11:59.605293Z","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-14T05:11:59.605359Z","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-14T05:11:59.605368Z","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-14T05:11:59.606024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-05-14T05:11:59.606168Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-05-14T05:11:59.607338Z","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-14T05:11:59.607599Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T05:11:59.607634Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T05:11:59.608188Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-05-14T05:11:59.608216Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-05-14T05:12:00.088402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T05:12:00.088463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T05:12:00.088486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-05-14T05:12:00.088516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-05-14T05:12:00.088533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-05-14T05:12:00.08855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-05-14T05:12:00.088568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-05-14T05:12:00.147895Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T05:12:00.148015Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T05:12:00.148042Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T05:12:00.148149Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T05:12:00.148193Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T05:12:00.148267Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T05:12:00.149855Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T05:12:00.149921Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T05:12:00.150017Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T05:12:00.150071Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T05:12:00.151158Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-05-14T05:12:07.884351Z","caller":"traceutil/trace.go:171","msg":"trace[1247353398] linearizableReadLoop","detail":"{readStateIndex:343; appliedIndex:342; }","duration":"107.047131ms","start":"2026-05-14T05:12:07.777266Z","end":"2026-05-14T05:12:07.884313Z","steps":["trace[1247353398] 'read index received' (duration: 81.685685ms)","trace[1247353398] 'applied index is now lower than readState.Index' (duration: 25.359946ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T05:12:07.884644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.320947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T05:12:07.884699Z","caller":"traceutil/trace.go:171","msg":"trace[660545309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:333; }","duration":"107.486742ms","start":"2026-05-14T05:12:07.777199Z","end":"2026-05-14T05:12:07.884686Z","steps":["trace[660545309] 'agreement among raft nodes before linearized reading' (duration: 107.272936ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:07.884447Z","caller":"traceutil/trace.go:171","msg":"trace[11298132] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"131.841801ms","start":"2026-05-14T05:12:07.752575Z","end":"2026-05-14T05:12:07.884417Z","steps":["trace[11298132] 'process raft request' (duration: 106.446315ms)","trace[11298132] 'compare' (duration: 25.065288ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:12:08.572925Z","caller":"traceutil/trace.go:171","msg":"trace[673342592] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"100.963916ms","start":"2026-05-14T05:12:08.471929Z","end":"2026-05-14T05:12:08.572893Z","steps":["trace[673342592] 'process raft request' (duration: 100.797812ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:08.623584Z","caller":"traceutil/trace.go:171","msg":"trace[306206648] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:351; }","duration":"122.213063ms","start":"2026-05-14T05:12:08.501333Z","end":"2026-05-14T05:12:08.623546Z","steps":["trace[306206648] 'read index received' (duration: 71.381712ms)","trace[306206648] 'applied index is now lower than readState.Index' (duration: 50.829191ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:12:08.623961Z","caller":"traceutil/trace.go:171","msg":"trace[195233321] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"145.685885ms","start":"2026-05-14T05:12:08.478235Z","end":"2026-05-14T05:12:08.62392Z","steps":["trace[195233321] 'process raft request' (duration: 145.103461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:08.624118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.753416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-14T05:12:08.624248Z","caller":"traceutil/trace.go:171","msg":"trace[1376079720] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:343; }","duration":"122.93574ms","start":"2026-05-14T05:12:08.501288Z","end":"2026-05-14T05:12:08.624224Z","steps":["trace[1376079720] 'agreement among raft nodes before linearized reading' (duration: 122.671444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:09.021135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.24515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-14T05:12:09.021237Z","caller":"traceutil/trace.go:171","msg":"trace[1075210489] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:345; }","duration":"120.414735ms","start":"2026-05-14T05:12:08.900799Z","end":"2026-05-14T05:12:09.021214Z","steps":["trace[1075210489] 'range keys from in-memory index tree' (duration: 120.150328ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:09.519755Z","caller":"traceutil/trace.go:171","msg":"trace[1597255621] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"149.213461ms","start":"2026-05-14T05:12:09.370518Z","end":"2026-05-14T05:12:09.519732Z","steps":["trace[1597255621] 'process raft request' (duration: 149.17391ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:09.519881Z","caller":"traceutil/trace.go:171","msg":"trace[513204389] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:375; }","duration":"169.835628ms","start":"2026-05-14T05:12:09.349993Z","end":"2026-05-14T05:12:09.519829Z","steps":["trace[513204389] 'read index received' (duration: 17.356357ms)","trace[513204389] 'applied index is now lower than readState.Index' (duration: 152.477671ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:12:09.519759Z","caller":"traceutil/trace.go:171","msg":"trace[628230479] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"177.856265ms","start":"2026-05-14T05:12:09.341854Z","end":"2026-05-14T05:12:09.51971Z","steps":["trace[628230479] 'process raft request' (duration: 147.372765ms)","trace[628230479] 'compare' (duration: 30.293946ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:12:09.520088Z","caller":"traceutil/trace.go:171","msg":"trace[653466859] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"178.188264ms","start":"2026-05-14T05:12:09.341872Z","end":"2026-05-14T05:12:09.52006Z","steps":["trace[653466859] 'process raft request' (duration: 177.775404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:09.520393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.366161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-14T05:12:09.520477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.607225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-14T05:12:09.520528Z","caller":"traceutil/trace.go:171","msg":"trace[1932126502] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:369; }","duration":"124.716367ms","start":"2026-05-14T05:12:09.395798Z","end":"2026-05-14T05:12:09.520514Z","steps":["trace[1932126502] 'agreement among raft nodes before linearized reading' (duration: 124.180884ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:09.52055Z","caller":"traceutil/trace.go:171","msg":"trace[234208007] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:369; }","duration":"170.575206ms","start":"2026-05-14T05:12:09.34992Z","end":"2026-05-14T05:12:09.520495Z","steps":["trace[234208007] 'agreement among raft nodes before linearized reading' (duration: 170.055654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:10.118261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.577712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T05:12:10.11862Z","caller":"traceutil/trace.go:171","msg":"trace[488835727] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"569.484835ms","start":"2026-05-14T05:12:09.54905Z","end":"2026-05-14T05:12:10.118535Z","steps":["trace[488835727] 'process raft request' (duration: 362.709013ms)","trace[488835727] 'compare' (duration: 205.392128ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T05:12:10.118776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:09.549021Z","time spent":"569.67407ms","remote":"127.0.0.1:49196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:10.119303Z","caller":"traceutil/trace.go:171","msg":"trace[1245101352] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:381; }","duration":"387.894577ms","start":"2026-05-14T05:12:09.731368Z","end":"2026-05-14T05:12:10.119262Z","steps":["trace[1245101352] 'read index received' (duration: 180.325806ms)","trace[1245101352] 'applied index is now lower than readState.Index' (duration: 207.567191ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:12:10.11943Z","caller":"traceutil/trace.go:171","msg":"trace[1941600157] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"563.022174ms","start":"2026-05-14T05:12:09.556358Z","end":"2026-05-14T05:12:10.11938Z","steps":["trace[1941600157] 'process raft request' (duration: 562.745578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:10.119471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.088522ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T05:12:10.119599Z","caller":"traceutil/trace.go:171","msg":"trace[950593190] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:373; }","duration":"388.228686ms","start":"2026-05-14T05:12:09.731353Z","end":"2026-05-14T05:12:10.119582Z","steps":["trace[950593190] 'agreement among raft nodes before linearized reading' (duration: 388.049352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:10.119641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:09.556342Z","time spent":"563.173268ms","remote":"127.0.0.1:49258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T05:12:10.119642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.12719ms","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-14T05:12:10.120013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.592466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T05:12:10.120076Z","caller":"traceutil/trace.go:171","msg":"trace[1211411903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:373; }","duration":"145.699019ms","start":"2026-05-14T05:12:09.974363Z","end":"2026-05-14T05:12:10.120062Z","steps":["trace[1211411903] 'agreement among raft nodes before linearized reading' (duration: 145.580216ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:10.120021Z","caller":"traceutil/trace.go:171","msg":"trace[1076534376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:373; }","duration":"368.55046ms","start":"2026-05-14T05:12:09.751443Z","end":"2026-05-14T05:12:10.119993Z","steps":["trace[1076534376] 'agreement among raft nodes before linearized reading' (duration: 368.062588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:10.120257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:09.751404Z","time spent":"368.837737ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T05:12:10.121732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.591502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T05:12:10.121806Z","caller":"traceutil/trace.go:171","msg":"trace[1984758883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:373; }","duration":"220.316075ms","start":"2026-05-14T05:12:09.901471Z","end":"2026-05-14T05:12:10.121787Z","steps":["trace[1984758883] 'agreement among raft nodes before linearized reading' (duration: 218.587213ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:10.705668Z","caller":"traceutil/trace.go:171","msg":"trace[1463894393] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"579.114931ms","start":"2026-05-14T05:12:10.126528Z","end":"2026-05-14T05:12:10.705643Z","steps":["trace[1463894393] 'process raft request' (duration: 578.930266ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:10.706003Z","caller":"traceutil/trace.go:171","msg":"trace[1071442168] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"578.903495ms","start":"2026-05-14T05:12:10.127073Z","end":"2026-05-14T05:12:10.705977Z","steps":["trace[1071442168] 'process raft request' (duration: 578.525666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:10.70631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.126497Z","time spent":"579.246124ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T05:12:10.706544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.127057Z","time spent":"579.012127ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:12:10.706522Z","caller":"traceutil/trace.go:171","msg":"trace[1899639711] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"579.221962ms","start":"2026-05-14T05:12:10.12724Z","end":"2026-05-14T05:12:10.706462Z","steps":["trace[1899639711] 'process raft request' (duration: 578.657359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:10.706722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.127229Z","time spent":"579.401237ms","remote":"127.0.0.1:49258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:10.70697Z","caller":"traceutil/trace.go:171","msg":"trace[889549532] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"578.460714ms","start":"2026-05-14T05:12:10.128467Z","end":"2026-05-14T05:12:10.706928Z","steps":["trace[889549532] 'process raft request' (duration: 577.798158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:10.707073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.128457Z","time spent":"578.571328ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:10.707247Z","caller":"traceutil/trace.go:171","msg":"trace[1420574695] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"578.699571ms","start":"2026-05-14T05:12:10.128535Z","end":"2026-05-14T05:12:10.707234Z","steps":["trace[1420574695] 'process raft request' (duration: 578.306561ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:10.707268Z","caller":"traceutil/trace.go:171","msg":"trace[1468209753] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"578.559217ms","start":"2026-05-14T05:12:10.128695Z","end":"2026-05-14T05:12:10.707254Z","steps":["trace[1468209753] 'process raft request' (duration: 578.200359ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:10.707264Z","caller":"traceutil/trace.go:171","msg":"trace[331035506] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"576.875646ms","start":"2026-05-14T05:12:10.13036Z","end":"2026-05-14T05:12:10.707236Z","steps":["trace[331035506] 'process raft request' (duration: 576.598049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:10.707326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.128529Z","time spent":"578.762812ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T05:12:10.707341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.128684Z","time spent":"578.617738ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T05:12:10.707374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.130351Z","time spent":"576.965658ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:12:10.707476Z","caller":"traceutil/trace.go:171","msg":"trace[853573630] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"575.591224ms","start":"2026-05-14T05:12:10.131874Z","end":"2026-05-14T05:12:10.707465Z","steps":["trace[853573630] 'process raft request' (duration: 575.144684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:10.707587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.131865Z","time spent":"575.686777ms","remote":"127.0.0.1:49258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:12:10.707194Z","caller":"traceutil/trace.go:171","msg":"trace[1094945053] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"573.909813ms","start":"2026-05-14T05:12:10.133265Z","end":"2026-05-14T05:12:10.707175Z","steps":["trace[1094945053] 'process raft request' (duration: 573.824271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:10.708059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.133246Z","time spent":"574.680332ms","remote":"127.0.0.1:49258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T05:12:11.029603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.83797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T05:12:11.029793Z","caller":"traceutil/trace.go:171","msg":"trace[1156959723] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"315.668011ms","start":"2026-05-14T05:12:10.714098Z","end":"2026-05-14T05:12:11.029766Z","steps":["trace[1156959723] 'process raft request' (duration: 169.531464ms)","trace[1156959723] 'compare' (duration: 145.602345ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T05:12:11.029871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.714074Z","time spent":"315.766844ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:11.034558Z","caller":"traceutil/trace.go:171","msg":"trace[739099733] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"317.802593ms","start":"2026-05-14T05:12:10.716736Z","end":"2026-05-14T05:12:11.034539Z","steps":["trace[739099733] 'process raft request' (duration: 317.450344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:11.034667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.716722Z","time spent":"317.882395ms","remote":"127.0.0.1:49518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:12:11.034707Z","caller":"traceutil/trace.go:171","msg":"trace[130755443] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"317.890675ms","start":"2026-05-14T05:12:10.716759Z","end":"2026-05-14T05:12:11.03465Z","steps":["trace[130755443] 'process raft request' (duration: 317.623338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:11.034866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.716737Z","time spent":"318.049649ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:11.035076Z","caller":"traceutil/trace.go:171","msg":"trace[345858378] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"317.354972ms","start":"2026-05-14T05:12:10.717678Z","end":"2026-05-14T05:12:11.035033Z","steps":["trace[345858378] 'process raft request' (duration: 316.786898ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:11.035233Z","caller":"traceutil/trace.go:171","msg":"trace[1391995252] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"313.15094ms","start":"2026-05-14T05:12:10.721957Z","end":"2026-05-14T05:12:11.035108Z","steps":["trace[1391995252] 'process raft request' (duration: 312.897214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:11.035497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.721931Z","time spent":"313.459596ms","remote":"127.0.0.1:49258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T05:12:11.035316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:10.717665Z","time spent":"317.509386ms","remote":"127.0.0.1:49258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T05:12:11.334781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.44991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T05:12:11.335079Z","caller":"traceutil/trace.go:171","msg":"trace[582443609] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"290.941713ms","start":"2026-05-14T05:12:11.044098Z","end":"2026-05-14T05:12:11.33504Z","steps":["trace[582443609] 'process raft request' (duration: 131.782796ms)","trace[582443609] 'compare' (duration: 158.033499ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:12:11.335126Z","caller":"traceutil/trace.go:171","msg":"trace[1748259195] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"289.638392ms","start":"2026-05-14T05:12:11.045469Z","end":"2026-05-14T05:12:11.335107Z","steps":["trace[1748259195] 'process raft request' (duration: 289.511959ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:11.586167Z","caller":"traceutil/trace.go:171","msg":"trace[1002031823] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"215.236302ms","start":"2026-05-14T05:12:11.370892Z","end":"2026-05-14T05:12:11.586128Z","steps":["trace[1002031823] 'process raft request' (duration: 117.324766ms)","trace[1002031823] 'compare' (duration: 97.756762ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:12:12.010968Z","caller":"traceutil/trace.go:171","msg":"trace[1627805316] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"155.509608ms","start":"2026-05-14T05:12:11.855431Z","end":"2026-05-14T05:12:12.010941Z","steps":["trace[1627805316] 'process raft request' (duration: 155.309093ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:12.44443Z","caller":"traceutil/trace.go:171","msg":"trace[175180056] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"102.330733ms","start":"2026-05-14T05:12:12.342051Z","end":"2026-05-14T05:12:12.444382Z","steps":["trace[175180056] 'process raft request' (duration: 61.113364ms)","trace[175180056] 'compare' (duration: 41.014484ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:12:13.054967Z","caller":"traceutil/trace.go:171","msg":"trace[1764013691] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"114.510783ms","start":"2026-05-14T05:12:12.939719Z","end":"2026-05-14T05:12:13.05423Z","steps":["trace[1764013691] 'process raft request' (duration: 103.896021ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:14.108797Z","caller":"traceutil/trace.go:171","msg":"trace[409095952] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"161.992088ms","start":"2026-05-14T05:12:13.946769Z","end":"2026-05-14T05:12:14.108761Z","steps":["trace[409095952] 'process raft request' (duration: 161.738062ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:25.055964Z","caller":"traceutil/trace.go:171","msg":"trace[741756423] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"102.477876ms","start":"2026-05-14T05:12:24.95346Z","end":"2026-05-14T05:12:25.055938Z","steps":["trace[741756423] 'read index received' (duration: 102.250851ms)","trace[741756423] 'applied index is now lower than readState.Index' (duration: 225.995µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:12:25.056306Z","caller":"traceutil/trace.go:171","msg":"trace[1227350051] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"111.796647ms","start":"2026-05-14T05:12:24.944498Z","end":"2026-05-14T05:12:25.056294Z","steps":["trace[1227350051] 'process raft request' (duration: 111.234125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:25.056524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.043358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T05:12:25.056569Z","caller":"traceutil/trace.go:171","msg":"trace[1324361145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:463; }","duration":"103.128399ms","start":"2026-05-14T05:12:24.953429Z","end":"2026-05-14T05:12:25.056557Z","steps":["trace[1324361145] 'agreement among raft nodes before linearized reading' (duration: 103.044187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:31.652683Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315852059797266,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T05:12:31.71111Z","caller":"traceutil/trace.go:171","msg":"trace[966725750] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"559.102291ms","start":"2026-05-14T05:12:31.151982Z","end":"2026-05-14T05:12:31.711085Z","steps":["trace[966725750] 'read index received' (duration: 557.760794ms)","trace[966725750] 'applied index is now lower than readState.Index' (duration: 1.340177ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T05:12:31.711075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.250021ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T05:12:31.71118Z","caller":"traceutil/trace.go:171","msg":"trace[718267865] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:476; }","duration":"185.376284ms","start":"2026-05-14T05:12:31.525793Z","end":"2026-05-14T05:12:31.711169Z","steps":["trace[718267865] 'range keys from in-memory index tree' (duration: 185.233371ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:31.711155Z","caller":"traceutil/trace.go:171","msg":"trace[1874724637] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"568.350831ms","start":"2026-05-14T05:12:31.142766Z","end":"2026-05-14T05:12:31.711117Z","steps":["trace[1874724637] 'process raft request' (duration: 566.908461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:31.711245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.262384ms","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-14T05:12:31.711278Z","caller":"traceutil/trace.go:171","msg":"trace[1990718197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:477; }","duration":"559.335136ms","start":"2026-05-14T05:12:31.151933Z","end":"2026-05-14T05:12:31.711268Z","steps":["trace[1990718197] 'agreement among raft nodes before linearized reading' (duration: 559.263324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:31.711316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:31.151912Z","time spent":"559.394467ms","remote":"127.0.0.1:49346","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-14T05:12:31.711307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.748082ms","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-14T05:12:31.711335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:31.142747Z","time spent":"568.484863ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:12:31.71139Z","caller":"traceutil/trace.go:171","msg":"trace[544654216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:477; }","duration":"554.871735ms","start":"2026-05-14T05:12:31.156494Z","end":"2026-05-14T05:12:31.711366Z","steps":["trace[544654216] 'agreement among raft nodes before linearized reading' (duration: 554.728321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:31.711442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:31.156481Z","time spent":"554.952747ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T05:12:35.129956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.780639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T05:12:35.130117Z","caller":"traceutil/trace.go:171","msg":"trace[784897459] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"706.018118ms","start":"2026-05-14T05:12:34.42408Z","end":"2026-05-14T05:12:35.130098Z","steps":["trace[784897459] 'process raft request' (duration: 291.008356ms)","trace[784897459] 'compare' (duration: 414.631385ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T05:12:35.130184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.424067Z","time spent":"706.0869ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T05:12:35.285636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315852059797356,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T05:12:35.359445Z","caller":"traceutil/trace.go:171","msg":"trace[1680138669] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"927.365999ms","start":"2026-05-14T05:12:34.432049Z","end":"2026-05-14T05:12:35.359415Z","steps":["trace[1680138669] 'process raft request' (duration: 927.054803ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:35.359487Z","caller":"traceutil/trace.go:171","msg":"trace[1189337321] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"927.283388ms","start":"2026-05-14T05:12:34.432161Z","end":"2026-05-14T05:12:35.359444Z","steps":["trace[1189337321] 'process raft request' (duration: 926.998982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.359599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.432042Z","time spent":"927.485251ms","remote":"127.0.0.1:49558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T05:12:35.359629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.432156Z","time spent":"927.4016ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:35.359622Z","caller":"traceutil/trace.go:171","msg":"trace[622073518] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"935.225768ms","start":"2026-05-14T05:12:34.424365Z","end":"2026-05-14T05:12:35.359591Z","steps":["trace[622073518] 'process raft request' (duration: 934.416742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.359747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.424359Z","time spent":"935.32715ms","remote":"127.0.0.1:49558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:35.359768Z","caller":"traceutil/trace.go:171","msg":"trace[714755305] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"935.34494ms","start":"2026-05-14T05:12:34.424414Z","end":"2026-05-14T05:12:35.359759Z","steps":["trace[714755305] 'process raft request' (duration: 934.655567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.359803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.424411Z","time spent":"935.377451ms","remote":"127.0.0.1:49558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:35.359878Z","caller":"traceutil/trace.go:171","msg":"trace[1674181450] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"927.613824ms","start":"2026-05-14T05:12:34.432258Z","end":"2026-05-14T05:12:35.359872Z","steps":["trace[1674181450] 'process raft request' (duration: 926.951301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.359913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.432236Z","time spent":"927.651885ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:12:35.359987Z","caller":"traceutil/trace.go:171","msg":"trace[1542914051] transaction","detail":"{read_only:false; number_of_response:1; response_revision:529; }","duration":"924.073953ms","start":"2026-05-14T05:12:34.435904Z","end":"2026-05-14T05:12:35.359978Z","steps":["trace[1542914051] 'process raft request' (duration: 923.350669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.360052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.435825Z","time spent":"924.185915ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7367,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:12:35.360127Z","caller":"traceutil/trace.go:171","msg":"trace[653034295] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"924.104044ms","start":"2026-05-14T05:12:34.436016Z","end":"2026-05-14T05:12:35.36012Z","steps":["trace[653034295] 'process raft request' (duration: 923.279218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.360162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.436011Z","time spent":"924.135585ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:35.360484Z","caller":"traceutil/trace.go:171","msg":"trace[386725044] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"767.528763ms","start":"2026-05-14T05:12:34.592949Z","end":"2026-05-14T05:12:35.360477Z","steps":["trace[386725044] 'process raft request' (duration: 767.495922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.360517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.592915Z","time spent":"767.588464ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:35.36059Z","caller":"traceutil/trace.go:171","msg":"trace[2000794471] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"920.51314ms","start":"2026-05-14T05:12:34.440073Z","end":"2026-05-14T05:12:35.360586Z","steps":["trace[2000794471] 'process raft request' (duration: 920.032231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.360617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.440065Z","time spent":"920.539952ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:35.360669Z","caller":"traceutil/trace.go:171","msg":"trace[1109553084] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"914.643813ms","start":"2026-05-14T05:12:34.446021Z","end":"2026-05-14T05:12:35.360665Z","steps":["trace[1109553084] 'process raft request' (duration: 914.258755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.360691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.44601Z","time spent":"914.672293ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:35.360801Z","caller":"traceutil/trace.go:171","msg":"trace[1700930177] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"872.066586ms","start":"2026-05-14T05:12:34.488728Z","end":"2026-05-14T05:12:35.360794Z","steps":["trace[1700930177] 'process raft request' (duration: 871.671369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.360834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.488713Z","time spent":"872.105817ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:35.360827Z","caller":"traceutil/trace.go:171","msg":"trace[1507084550] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"900.429077ms","start":"2026-05-14T05:12:34.460383Z","end":"2026-05-14T05:12:35.360812Z","steps":["trace[1507084550] 'process raft request' (duration: 899.953147ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:35.360929Z","caller":"traceutil/trace.go:171","msg":"trace[778574864] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"898.223252ms","start":"2026-05-14T05:12:34.462652Z","end":"2026-05-14T05:12:35.360875Z","steps":["trace[778574864] 'process raft request' (duration: 897.713922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.36096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.462639Z","time spent":"898.308883ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T05:12:35.361007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.460352Z","time spent":"900.5776ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:35.360878Z","caller":"traceutil/trace.go:171","msg":"trace[1497008699] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"908.777635ms","start":"2026-05-14T05:12:34.452088Z","end":"2026-05-14T05:12:35.360865Z","steps":["trace[1497008699] 'process raft request' (duration: 908.217434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.361106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.452078Z","time spent":"909.009159ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:35.365868Z","caller":"traceutil/trace.go:171","msg":"trace[92980233] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:542; }","duration":"580.751444ms","start":"2026-05-14T05:12:34.785105Z","end":"2026-05-14T05:12:35.365857Z","steps":["trace[92980233] 'read index received' (duration: 573.689803ms)","trace[92980233] 'applied index is now lower than readState.Index' (duration: 7.060721ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:12:35.365909Z","caller":"traceutil/trace.go:171","msg":"trace[1224754454] transaction","detail":"{read_only:false; number_of_response:1; response_revision:538; }","duration":"226.326661ms","start":"2026-05-14T05:12:35.139567Z","end":"2026-05-14T05:12:35.365893Z","steps":["trace[1224754454] 'process raft request' (duration: 226.25378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.366014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.916097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T05:12:35.366041Z","caller":"traceutil/trace.go:171","msg":"trace[1801014094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"580.993708ms","start":"2026-05-14T05:12:34.785042Z","end":"2026-05-14T05:12:35.366035Z","steps":["trace[1801014094] 'agreement among raft nodes before linearized reading' (duration: 580.857216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:35.366059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:34.785012Z","time spent":"581.04413ms","remote":"127.0.0.1:49066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T05:12:35.366268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.987155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-14T05:12:35.365879Z","caller":"traceutil/trace.go:171","msg":"trace[1828973688] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"227.899752ms","start":"2026-05-14T05:12:35.13797Z","end":"2026-05-14T05:12:35.36587Z","steps":["trace[1828973688] 'process raft request' (duration: 227.737239ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:35.3663Z","caller":"traceutil/trace.go:171","msg":"trace[1766954382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"213.055096ms","start":"2026-05-14T05:12:35.153237Z","end":"2026-05-14T05:12:35.366292Z","steps":["trace[1766954382] 'agreement among raft nodes before linearized reading' (duration: 212.710739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:39.237509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.585204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T05:12:39.237581Z","caller":"traceutil/trace.go:171","msg":"trace[768055575] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:610; }","duration":"100.655865ms","start":"2026-05-14T05:12:39.136902Z","end":"2026-05-14T05:12:39.237558Z","steps":["trace[768055575] 'range keys from in-memory index tree' (duration: 100.505902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:39.237665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.724136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T05:12:39.237716Z","caller":"traceutil/trace.go:171","msg":"trace[686315790] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:610; }","duration":"100.798868ms","start":"2026-05-14T05:12:39.136913Z","end":"2026-05-14T05:12:39.237712Z","steps":["trace[686315790] 'count revisions from in-memory index tree' (duration: 100.669355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:41.631535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.999141ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T05:12:41.631654Z","caller":"traceutil/trace.go:171","msg":"trace[941360959] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:624; }","duration":"106.131653ms","start":"2026-05-14T05:12:41.525503Z","end":"2026-05-14T05:12:41.631634Z","steps":["trace[941360959] 'range keys from in-memory index tree' (duration: 105.986301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:41.63176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.471592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T05:12:41.631994Z","caller":"traceutil/trace.go:171","msg":"trace[2117527734] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"139.608144ms","start":"2026-05-14T05:12:41.49237Z","end":"2026-05-14T05:12:41.631978Z","steps":["trace[2117527734] 'process raft request' (duration: 139.540533ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:41.632108Z","caller":"traceutil/trace.go:171","msg":"trace[1233757956] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"188.774296ms","start":"2026-05-14T05:12:41.443209Z","end":"2026-05-14T05:12:41.631983Z","steps":["trace[1233757956] 'process raft request' (duration: 85.002578ms)","trace[1233757956] 'compare' (duration: 103.33868ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T05:12:42.333378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.624823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T05:12:42.333485Z","caller":"traceutil/trace.go:171","msg":"trace[413607879] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"301.308272ms","start":"2026-05-14T05:12:42.032161Z","end":"2026-05-14T05:12:42.33347Z","steps":["trace[413607879] 'read index received' (duration: 42.571µs)","trace[413607879] 'applied index is now lower than readState.Index' (duration: 301.264811ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T05:12:42.333657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.488885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q7cdv\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-14T05:12:42.333686Z","caller":"traceutil/trace.go:171","msg":"trace[1300021742] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q7cdv; range_end:; response_count:1; response_revision:631; }","duration":"301.570066ms","start":"2026-05-14T05:12:42.032109Z","end":"2026-05-14T05:12:42.333679Z","steps":["trace[1300021742] 'agreement among raft nodes before linearized reading' (duration: 301.411473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:42.333711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:42.032087Z","time spent":"301.617817ms","remote":"127.0.0.1:49258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-q7cdv\" "} {"level":"info","ts":"2026-05-14T05:12:42.333657Z","caller":"traceutil/trace.go:171","msg":"trace[1010494166] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"361.474971ms","start":"2026-05-14T05:12:41.972105Z","end":"2026-05-14T05:12:42.33358Z","steps":["trace[1010494166] 'process raft request' (duration: 53.589933ms)","trace[1010494166] 'compare' (duration: 307.520361ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T05:12:42.333874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:41.972081Z","time spent":"361.687605ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:42.457201Z","caller":"traceutil/trace.go:171","msg":"trace[1023291275] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"404.658137ms","start":"2026-05-14T05:12:42.05249Z","end":"2026-05-14T05:12:42.457148Z","steps":["trace[1023291275] 'process raft request' (duration: 384.155588ms)","trace[1023291275] 'compare' (duration: 19.630603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T05:12:42.462806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:42.052471Z","time spent":"410.230904ms","remote":"127.0.0.1:49182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:12:42.476048Z","caller":"traceutil/trace.go:171","msg":"trace[2048320139] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"127.308982ms","start":"2026-05-14T05:12:42.348708Z","end":"2026-05-14T05:12:42.476017Z","steps":["trace[2048320139] 'process raft request' (duration: 126.413695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:43.088924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.979082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T05:12:43.089158Z","caller":"traceutil/trace.go:171","msg":"trace[1409353783] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"571.386859ms","start":"2026-05-14T05:12:42.51772Z","end":"2026-05-14T05:12:43.089106Z","steps":["trace[1409353783] 'process raft request' (duration: 93.105461ms)","trace[1409353783] 'compare' (duration: 477.796479ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:12:43.08939Z","caller":"traceutil/trace.go:171","msg":"trace[1895294680] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"561.799006ms","start":"2026-05-14T05:12:42.52757Z","end":"2026-05-14T05:12:43.089369Z","steps":["trace[1895294680] 'process raft request' (duration: 561.4641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:43.090134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:42.51769Z","time spent":"572.302755ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T05:12:43.090253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:42.527547Z","time spent":"562.639742ms","remote":"127.0.0.1:49326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:43.089383Z","caller":"traceutil/trace.go:171","msg":"trace[801171675] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"310.2262ms","start":"2026-05-14T05:12:42.779123Z","end":"2026-05-14T05:12:43.089349Z","steps":["trace[801171675] 'read index received' (duration: 299.729892ms)","trace[801171675] 'applied index is now lower than readState.Index' (duration: 10.494868ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T05:12:43.089524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.383453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T05:12:43.090496Z","caller":"traceutil/trace.go:171","msg":"trace[629277188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"311.382233ms","start":"2026-05-14T05:12:42.779078Z","end":"2026-05-14T05:12:43.09046Z","steps":["trace[629277188] 'agreement among raft nodes before linearized reading' (duration: 310.369743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:43.090601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:42.779052Z","time spent":"311.512135ms","remote":"127.0.0.1:49064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T05:12:43.536153Z","caller":"traceutil/trace.go:171","msg":"trace[1078652649] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"370.795608ms","start":"2026-05-14T05:12:43.165294Z","end":"2026-05-14T05:12:43.536089Z","steps":["trace[1078652649] 'read index received' (duration: 370.638055ms)","trace[1078652649] 'applied index is now lower than readState.Index' (duration: 156.893µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:12:43.536198Z","caller":"traceutil/trace.go:171","msg":"trace[808815260] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"431.705437ms","start":"2026-05-14T05:12:43.104462Z","end":"2026-05-14T05:12:43.536168Z","steps":["trace[808815260] 'process raft request' (duration: 431.524274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:43.536332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.023192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-14T05:12:43.536324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:43.104452Z","time spent":"431.80769ms","remote":"127.0.0.1:49258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:43.536365Z","caller":"traceutil/trace.go:171","msg":"trace[1914576216] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:636; }","duration":"371.096773ms","start":"2026-05-14T05:12:43.165259Z","end":"2026-05-14T05:12:43.536356Z","steps":["trace[1914576216] 'agreement among raft nodes before linearized reading' (duration: 370.9522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:43.53639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:43.165242Z","time spent":"371.142634ms","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"warn","ts":"2026-05-14T05:12:44.301044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.226749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T05:12:44.301133Z","caller":"traceutil/trace.go:171","msg":"trace[802573204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"451.363611ms","start":"2026-05-14T05:12:43.849748Z","end":"2026-05-14T05:12:44.301112Z","steps":["trace[802573204] 'range keys from in-memory index tree' (duration: 451.027465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:44.301179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:43.849727Z","time spent":"451.441743ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T05:12:44.301309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.864232ms","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-14T05:12:44.301377Z","caller":"traceutil/trace.go:171","msg":"trace[85174352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"450.961254ms","start":"2026-05-14T05:12:43.850396Z","end":"2026-05-14T05:12:44.301357Z","steps":["trace[85174352] 'range keys from in-memory index tree' (duration: 450.695839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:44.301424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:43.850385Z","time spent":"451.030455ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T05:12:44.301533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.933222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T05:12:44.301614Z","caller":"traceutil/trace.go:171","msg":"trace[1351633751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"470.041735ms","start":"2026-05-14T05:12:43.831554Z","end":"2026-05-14T05:12:44.301596Z","steps":["trace[1351633751] 'range keys from in-memory index tree' (duration: 469.78996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:44.301659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:43.831542Z","time spent":"470.108326ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T05:12:44.302063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.941193ms","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-14T05:12:44.302117Z","caller":"traceutil/trace.go:171","msg":"trace[663323705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"660.020464ms","start":"2026-05-14T05:12:43.642085Z","end":"2026-05-14T05:12:44.302105Z","steps":["trace[663323705] 'range keys from in-memory index tree' (duration: 659.2288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:44.302151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:43.642073Z","time spent":"660.069296ms","remote":"127.0.0.1:49346","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-14T05:12:44.301548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.475974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T05:12:44.302591Z","caller":"traceutil/trace.go:171","msg":"trace[824876892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"524.564714ms","start":"2026-05-14T05:12:43.77801Z","end":"2026-05-14T05:12:44.302575Z","steps":["trace[824876892] 'range keys from in-memory index tree' (duration: 523.383112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:44.302645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:43.777986Z","time spent":"524.645446ms","remote":"127.0.0.1:49066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T05:12:45.091991Z","caller":"traceutil/trace.go:171","msg":"trace[665681869] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"786.451391ms","start":"2026-05-14T05:12:44.305514Z","end":"2026-05-14T05:12:45.091965Z","steps":["trace[665681869] 'process raft request' (duration: 786.232497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:45.092152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:44.305493Z","time spent":"786.574983ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:12:45.688268Z","caller":"traceutil/trace.go:171","msg":"trace[1843853484] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:664; }","duration":"910.172029ms","start":"2026-05-14T05:12:44.778072Z","end":"2026-05-14T05:12:45.688244Z","steps":["trace[1843853484] 'read index received' (duration: 313.654136ms)","trace[1843853484] 'applied index is now lower than readState.Index' (duration: 596.517043ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:12:45.688304Z","caller":"traceutil/trace.go:171","msg":"trace[426254535] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.381503337s","start":"2026-05-14T05:12:44.306765Z","end":"2026-05-14T05:12:45.688268Z","steps":["trace[426254535] 'process raft request' (duration: 1.381265832s)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:45.688372Z","caller":"traceutil/trace.go:171","msg":"trace[1772455209] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.38117604s","start":"2026-05-14T05:12:44.307167Z","end":"2026-05-14T05:12:45.688343Z","steps":["trace[1772455209] 'process raft request' (duration: 1.381042838s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:45.688484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.352613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T05:12:45.688689Z","caller":"traceutil/trace.go:171","msg":"trace[82960658] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.381202051s","start":"2026-05-14T05:12:44.307123Z","end":"2026-05-14T05:12:45.688325Z","steps":["trace[82960658] 'process raft request' (duration: 1.381046088s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:45.68875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:44.306743Z","time spent":"1.381649209s","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:12:45.688732Z","caller":"traceutil/trace.go:171","msg":"trace[1696147280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"910.680819ms","start":"2026-05-14T05:12:44.778018Z","end":"2026-05-14T05:12:45.688699Z","steps":["trace[1696147280] 'agreement among raft nodes before linearized reading' (duration: 910.343422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:45.688883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:44.777999Z","time spent":"910.875513ms","remote":"127.0.0.1:49066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T05:12:45.688889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:44.307111Z","time spent":"1.381677869s","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T05:12:45.688502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:44.306965Z","time spent":"1.381463276s","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:12:46.172017Z","caller":"traceutil/trace.go:171","msg":"trace[1306345626] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"113.022414ms","start":"2026-05-14T05:12:46.058965Z","end":"2026-05-14T05:12:46.171988Z","steps":["trace[1306345626] 'process raft request' (duration: 112.83952ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:47.598324Z","caller":"traceutil/trace.go:171","msg":"trace[760445966] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"426.44238ms","start":"2026-05-14T05:12:47.171852Z","end":"2026-05-14T05:12:47.598294Z","steps":["trace[760445966] 'process raft request' (duration: 426.114874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:47.599058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:47.171819Z","time spent":"426.596972ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:47.614271Z","caller":"traceutil/trace.go:171","msg":"trace[1253836871] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"441.133149ms","start":"2026-05-14T05:12:47.173122Z","end":"2026-05-14T05:12:47.614255Z","steps":["trace[1253836871] 'process raft request' (duration: 440.661681ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:47.614279Z","caller":"traceutil/trace.go:171","msg":"trace[533726659] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"440.760532ms","start":"2026-05-14T05:12:47.1735Z","end":"2026-05-14T05:12:47.61426Z","steps":["trace[533726659] 'process raft request' (duration: 440.606399ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:47.614282Z","caller":"traceutil/trace.go:171","msg":"trace[1248540414] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:684; }","duration":"435.825761ms","start":"2026-05-14T05:12:47.178433Z","end":"2026-05-14T05:12:47.614259Z","steps":["trace[1248540414] 'read index received' (duration: 419.743546ms)","trace[1248540414] 'applied index is now lower than readState.Index' (duration: 16.081065ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T05:12:47.614353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:47.17347Z","time spent":"440.851703ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T05:12:47.614403Z","caller":"traceutil/trace.go:171","msg":"trace[1839899858] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"436.062605ms","start":"2026-05-14T05:12:47.17832Z","end":"2026-05-14T05:12:47.614383Z","steps":["trace[1839899858] 'process raft request' (duration: 435.870352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:47.614429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.970834ms","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-14T05:12:47.614484Z","caller":"traceutil/trace.go:171","msg":"trace[356377855] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:666; }","duration":"436.042625ms","start":"2026-05-14T05:12:47.178428Z","end":"2026-05-14T05:12:47.61447Z","steps":["trace[356377855] 'agreement among raft nodes before linearized reading' (duration: 435.947353ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:47.614456Z","caller":"traceutil/trace.go:171","msg":"trace[1339981063] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"438.346917ms","start":"2026-05-14T05:12:47.176061Z","end":"2026-05-14T05:12:47.614408Z","steps":["trace[1339981063] 'process raft request' (duration: 438.093632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:12:47.614521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:47.178395Z","time spent":"436.115956ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-14T05:12:47.614582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:47.178313Z","time spent":"436.156257ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T05:12:47.614656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:47.176052Z","time spent":"438.479129ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T05:12:47.614776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:12:47.173063Z","time spent":"441.244371ms","remote":"127.0.0.1:49134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T05:12:47.621864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.192211ms","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-14T05:12:47.621917Z","caller":"traceutil/trace.go:171","msg":"trace[727376674] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:668; }","duration":"252.289433ms","start":"2026-05-14T05:12:47.369614Z","end":"2026-05-14T05:12:47.621903Z","steps":["trace[727376674] 'agreement among raft nodes before linearized reading' (duration: 252.151921ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:12:50.359219Z","caller":"traceutil/trace.go:171","msg":"trace[692111464] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"141.538245ms","start":"2026-05-14T05:12:50.217655Z","end":"2026-05-14T05:12:50.359194Z","steps":["trace[692111464] 'process raft request' (duration: 141.074197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:13:01.325585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.130366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T05:13:01.325647Z","caller":"traceutil/trace.go:171","msg":"trace[997666808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"546.237838ms","start":"2026-05-14T05:13:00.779396Z","end":"2026-05-14T05:13:01.325634Z","steps":["trace[997666808] 'range keys from in-memory index tree' (duration: 546.048614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:13:01.325673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:13:00.779377Z","time spent":"546.289718ms","remote":"127.0.0.1:49066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T05:13:01.32567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.652312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T05:13:01.32572Z","caller":"traceutil/trace.go:171","msg":"trace[1175176624] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:794; }","duration":"423.744114ms","start":"2026-05-14T05:13:00.901961Z","end":"2026-05-14T05:13:01.325705Z","steps":["trace[1175176624] 'count revisions from in-memory index tree' (duration: 423.547431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:13:01.325755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:13:00.901934Z","time spent":"423.812286ms","remote":"127.0.0.1:49518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-05-14T05:13:01.429216Z","caller":"traceutil/trace.go:171","msg":"trace[517758504] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"279.337574ms","start":"2026-05-14T05:13:01.149858Z","end":"2026-05-14T05:13:01.429196Z","steps":["trace[517758504] 'process raft request' (duration: 279.210062ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:13:01.429607Z","caller":"traceutil/trace.go:171","msg":"trace[409420447] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:824; }","duration":"250.601817ms","start":"2026-05-14T05:13:01.178959Z","end":"2026-05-14T05:13:01.42956Z","steps":["trace[409420447] 'read index received' (duration: 250.576867ms)","trace[409420447] 'applied index is now lower than readState.Index' (duration: 20.85µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T05:13:01.429895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.470433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T05:13:01.429938Z","caller":"traceutil/trace.go:171","msg":"trace[467118078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"102.580244ms","start":"2026-05-14T05:13:01.327347Z","end":"2026-05-14T05:13:01.429927Z","steps":["trace[467118078] 'agreement among raft nodes before linearized reading' (duration: 102.441332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:13:01.429922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.944703ms","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-14T05:13:01.430021Z","caller":"traceutil/trace.go:171","msg":"trace[1287258007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:795; }","duration":"251.096735ms","start":"2026-05-14T05:13:01.178904Z","end":"2026-05-14T05:13:01.430001Z","steps":["trace[1287258007] 'agreement among raft nodes before linearized reading' (duration: 250.801101ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:14:02.088614Z","caller":"traceutil/trace.go:171","msg":"trace[1567980144] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"110.713499ms","start":"2026-05-14T05:14:01.97787Z","end":"2026-05-14T05:14:02.088584Z","steps":["trace[1567980144] 'process raft request' (duration: 110.575666ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:14:16.393099Z","caller":"traceutil/trace.go:171","msg":"trace[948146537] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"111.359816ms","start":"2026-05-14T05:14:16.281711Z","end":"2026-05-14T05:14:16.393071Z","steps":["trace[948146537] 'process raft request' (duration: 111.182333ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:14:16.395477Z","caller":"traceutil/trace.go:171","msg":"trace[1040043406] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"113.145358ms","start":"2026-05-14T05:14:16.282309Z","end":"2026-05-14T05:14:16.395454Z","steps":["trace[1040043406] 'process raft request' (duration: 113.010286ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:14:39.627803Z","caller":"traceutil/trace.go:171","msg":"trace[1108381790] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"118.774883ms","start":"2026-05-14T05:14:39.508917Z","end":"2026-05-14T05:14:39.627692Z","steps":["trace[1108381790] 'process raft request' (duration: 92.304241ms)","trace[1108381790] 'compare' (duration: 26.201478ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:14:54.786015Z","caller":"traceutil/trace.go:171","msg":"trace[1731349859] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1396; }","duration":"135.68749ms","start":"2026-05-14T05:14:54.650306Z","end":"2026-05-14T05:14:54.785994Z","steps":["trace[1731349859] 'read index received' (duration: 135.550749ms)","trace[1731349859] 'applied index is now lower than readState.Index' (duration: 135.691µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:14:54.786141Z","caller":"traceutil/trace.go:171","msg":"trace[1273476128] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"162.697571ms","start":"2026-05-14T05:14:54.623431Z","end":"2026-05-14T05:14:54.786129Z","steps":["trace[1273476128] 'process raft request' (duration: 162.447968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:14:54.786336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.025062ms","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-14T05:14:54.786395Z","caller":"traceutil/trace.go:171","msg":"trace[221424751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1340; }","duration":"119.111164ms","start":"2026-05-14T05:14:54.667269Z","end":"2026-05-14T05:14:54.78638Z","steps":["trace[221424751] 'agreement among raft nodes before linearized reading' (duration: 118.968262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:14:54.786651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.266278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-14T05:14:54.786672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.355968ms","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-14T05:14:54.786716Z","caller":"traceutil/trace.go:171","msg":"trace[1710486195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1340; }","duration":"136.434788ms","start":"2026-05-14T05:14:54.650269Z","end":"2026-05-14T05:14:54.786704Z","steps":["trace[1710486195] 'agreement among raft nodes before linearized reading' (duration: 136.274296ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:14:54.786714Z","caller":"traceutil/trace.go:171","msg":"trace[118403354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1340; }","duration":"115.400749ms","start":"2026-05-14T05:14:54.671299Z","end":"2026-05-14T05:14:54.7867Z","steps":["trace[118403354] 'agreement among raft nodes before linearized reading' (duration: 115.198687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:14:55.288805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.907741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T05:14:55.288923Z","caller":"traceutil/trace.go:171","msg":"trace[118548196] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"497.251787ms","start":"2026-05-14T05:14:54.79165Z","end":"2026-05-14T05:14:55.288902Z","steps":["trace[118548196] 'process raft request' (duration: 97.149563ms)","trace[118548196] 'compare' (duration: 399.667269ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T05:14:55.288966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:14:54.79163Z","time spent":"497.315508ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:14:55.421865Z","caller":"traceutil/trace.go:171","msg":"trace[1343410362] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"630.126712ms","start":"2026-05-14T05:14:54.791712Z","end":"2026-05-14T05:14:55.421839Z","steps":["trace[1343410362] 'process raft request' (duration: 623.990409ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T05:14:55.421898Z","caller":"traceutil/trace.go:171","msg":"trace[953695971] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"629.560965ms","start":"2026-05-14T05:14:54.792316Z","end":"2026-05-14T05:14:55.421877Z","steps":["trace[953695971] 'process raft request' (duration: 629.462364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:14:55.421983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:14:54.791693Z","time spent":"630.235593ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T05:14:55.422025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:14:54.79231Z","time spent":"629.641006ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:14:55.422068Z","caller":"traceutil/trace.go:171","msg":"trace[1191526446] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1397; }","duration":"629.299482ms","start":"2026-05-14T05:14:54.792748Z","end":"2026-05-14T05:14:55.422047Z","steps":["trace[1191526446] 'read index received' (duration: 96.231392ms)","trace[1191526446] 'applied index is now lower than readState.Index' (duration: 533.06666ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T05:14:55.422138Z","caller":"traceutil/trace.go:171","msg":"trace[1584794903] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"629.738667ms","start":"2026-05-14T05:14:54.792392Z","end":"2026-05-14T05:14:55.42213Z","steps":["trace[1584794903] 'process raft request' (duration: 629.456464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:14:55.422175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:14:54.792385Z","time spent":"629.768507ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T05:14:55.42222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.476984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2574"} {"level":"info","ts":"2026-05-14T05:14:55.422255Z","caller":"traceutil/trace.go:171","msg":"trace[1324216907] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1344; }","duration":"629.543605ms","start":"2026-05-14T05:14:54.792701Z","end":"2026-05-14T05:14:55.422244Z","steps":["trace[1324216907] 'agreement among raft nodes before linearized reading' (duration: 629.403244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:14:55.422293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:14:54.79269Z","time spent":"629.595216ms","remote":"127.0.0.1:49258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2598,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-05-14T05:14:55.425691Z","caller":"traceutil/trace.go:171","msg":"trace[814626033] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"330.903071ms","start":"2026-05-14T05:14:55.094762Z","end":"2026-05-14T05:14:55.425665Z","steps":["trace[814626033] 'process raft request' (duration: 330.77074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:14:55.425834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:14:55.094726Z","time spent":"331.035664ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T05:14:56.358418Z","caller":"traceutil/trace.go:171","msg":"trace[588463861] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"515.573226ms","start":"2026-05-14T05:14:55.842779Z","end":"2026-05-14T05:14:56.358352Z","steps":["trace[588463861] 'process raft request' (duration: 515.383544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T05:14:56.358613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T05:14:55.842751Z","time spent":"515.760388ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}