{"level":"warn","ts":"2026-05-11T21:03:38.592472Z","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-11T21:03:38.59255Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-11T21:03:38.592608Z","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-11T21:03:38.592618Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-11T21:03:38.592633Z","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-11T21:03:38.593218Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-05-11T21:03:38.593281Z","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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-11T21:03:38.597871Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.415066ms"} {"level":"info","ts":"2026-05-11T21:03:38.60907Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-05-11T21:03:38.609183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T21:03:38.609217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-05-11T21:03:38.609233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T21:03:38.609249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-05-11T21:03:38.609289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-05-11T21:03:38.61785Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T21:03:38.619714Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T21:03:38.621524Z","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-11T21:03:38.623841Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T21:03:38.625Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T21:03:38.62635Z","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-11T21:03:38.626432Z","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-11T21:03:38.626442Z","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-11T21:03:38.628475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-05-11T21:03:38.628561Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-11T21:03:38.629589Z","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-11T21:03:38.629795Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T21:03:38.629828Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T21:03:38.629888Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-11T21:03:38.629896Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-11T21:03:39.010135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T21:03:39.010203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T21:03:39.010304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-05-11T21:03:39.010334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-05-11T21:03:39.010346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-11T21:03:39.010368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-05-11T21:03:39.010382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-11T21:03:39.012365Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T21:03:39.01306Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T21:03:39.013069Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T21:03:39.01322Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T21:03:39.013363Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T21:03:39.013508Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T21:03:39.013718Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T21:03:39.013851Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T21:03:39.013904Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T21:03:39.015752Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T21:03:39.017135Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-05-11T21:03:43.919995Z","caller":"traceutil/trace.go:171","msg":"trace[2005294976] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"389.403507ms","start":"2026-05-11T21:03:43.530562Z","end":"2026-05-11T21:03:43.919965Z","steps":["trace[2005294976] 'process raft request' (duration: 389.204161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:03:43.920648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:03:43.530531Z","time spent":"389.565272ms","remote":"127.0.0.1:57378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:03:44.803307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.029771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T21:03:44.803445Z","caller":"traceutil/trace.go:171","msg":"trace[1303954063] linearizableReadLoop","detail":"{readStateIndex:259; appliedIndex:258; }","duration":"595.043986ms","start":"2026-05-11T21:03:44.208385Z","end":"2026-05-11T21:03:44.803428Z","steps":["trace[1303954063] 'read index received' (duration: 40.992µs)","trace[1303954063] 'applied index is now lower than readState.Index' (duration: 595.001134ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:03:44.803502Z","caller":"traceutil/trace.go:171","msg":"trace[866177699] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"875.407288ms","start":"2026-05-11T21:03:43.928045Z","end":"2026-05-11T21:03:44.803452Z","steps":["trace[866177699] 'process raft request' (duration: 234.448319ms)","trace[866177699] 'compare' (duration: 639.794313ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:03:44.803606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.634513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:03:44.80364Z","caller":"traceutil/trace.go:171","msg":"trace[1186042162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:253; }","duration":"414.696005ms","start":"2026-05-11T21:03:44.388935Z","end":"2026-05-11T21:03:44.803631Z","steps":["trace[1186042162] 'agreement among raft nodes before linearized reading' (duration: 414.656023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:03:44.803671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:03:44.388912Z","time spent":"414.751897ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T21:03:44.803691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:03:43.928029Z","time spent":"875.584864ms","remote":"127.0.0.1:57318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T21:03:44.803564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.18035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:03:44.803984Z","caller":"traceutil/trace.go:171","msg":"trace[345369768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:253; }","duration":"595.613193ms","start":"2026-05-11T21:03:44.208329Z","end":"2026-05-11T21:03:44.803942Z","steps":["trace[345369768] 'agreement among raft nodes before linearized reading' (duration: 595.18188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:03:44.804076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:03:44.208307Z","time spent":"595.737777ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T21:04:09.156443Z","caller":"traceutil/trace.go:171","msg":"trace[475110010] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"320.215625ms","start":"2026-05-11T21:04:08.836153Z","end":"2026-05-11T21:04:09.156369Z","steps":["trace[475110010] 'process raft request' (duration: 319.439317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:09.156595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:08.836137Z","time spent":"320.390811ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T21:04:09.166162Z","caller":"traceutil/trace.go:171","msg":"trace[1349788992] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:526; }","duration":"236.043556ms","start":"2026-05-11T21:04:08.930085Z","end":"2026-05-11T21:04:09.166128Z","steps":["trace[1349788992] 'read index received' (duration: 225.55736ms)","trace[1349788992] 'applied index is now lower than readState.Index' (duration: 10.484166ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:04:09.166412Z","caller":"traceutil/trace.go:171","msg":"trace[813842438] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"299.862294ms","start":"2026-05-11T21:04:08.866536Z","end":"2026-05-11T21:04:09.166398Z","steps":["trace[813842438] 'process raft request' (duration: 299.329015ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:09.166498Z","caller":"traceutil/trace.go:171","msg":"trace[998429262] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"300.592ms","start":"2026-05-11T21:04:08.865898Z","end":"2026-05-11T21:04:09.16649Z","steps":["trace[998429262] 'process raft request' (duration: 299.911896ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:09.166583Z","caller":"traceutil/trace.go:171","msg":"trace[363009520] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"330.186992ms","start":"2026-05-11T21:04:08.836385Z","end":"2026-05-11T21:04:09.166572Z","steps":["trace[363009520] 'process raft request' (duration: 329.259869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:09.166623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:08.865879Z","time spent":"300.695974ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T21:04:09.166593Z","caller":"traceutil/trace.go:171","msg":"trace[1691559536] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"300.55623ms","start":"2026-05-11T21:04:08.865993Z","end":"2026-05-11T21:04:09.166549Z","steps":["trace[1691559536] 'process raft request' (duration: 299.845284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:09.166703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:08.836379Z","time spent":"330.256605ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T21:04:09.166509Z","caller":"traceutil/trace.go:171","msg":"trace[1261819101] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"309.975508ms","start":"2026-05-11T21:04:08.856489Z","end":"2026-05-11T21:04:09.166465Z","steps":["trace[1261819101] 'process raft request' (duration: 309.257542ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:09.166449Z","caller":"traceutil/trace.go:171","msg":"trace[5023287] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"286.504186ms","start":"2026-05-11T21:04:08.879922Z","end":"2026-05-11T21:04:09.166426Z","steps":["trace[5023287] 'process raft request' (duration: 286.031079ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:09.166839Z","caller":"traceutil/trace.go:171","msg":"trace[910659356] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"317.511268ms","start":"2026-05-11T21:04:08.849317Z","end":"2026-05-11T21:04:09.166829Z","steps":["trace[910659356] 'process raft request' (duration: 316.400998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:09.166813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:08.865987Z","time spent":"300.741946ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T21:04:09.166888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:08.849294Z","time spent":"317.57295ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T21:04:09.16687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:08.856478Z","time spent":"310.267768ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T21:04:09.16695Z","caller":"traceutil/trace.go:171","msg":"trace[2020304137] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"299.862544ms","start":"2026-05-11T21:04:08.867073Z","end":"2026-05-11T21:04:09.166935Z","steps":["trace[2020304137] 'process raft request' (duration: 298.815457ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:09.167Z","caller":"traceutil/trace.go:171","msg":"trace[1955466213] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"271.953983ms","start":"2026-05-11T21:04:08.895039Z","end":"2026-05-11T21:04:09.166993Z","steps":["trace[1955466213] 'process raft request' (duration: 270.974208ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:09.166973Z","caller":"traceutil/trace.go:171","msg":"trace[496881296] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"177.184475ms","start":"2026-05-11T21:04:08.989779Z","end":"2026-05-11T21:04:09.166964Z","steps":["trace[496881296] 'process raft request' (duration: 176.305914ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:09.16709Z","caller":"traceutil/trace.go:171","msg":"trace[679967227] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"293.31551ms","start":"2026-05-11T21:04:08.873738Z","end":"2026-05-11T21:04:09.167054Z","steps":["trace[679967227] 'process raft request' (duration: 292.181469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:09.16717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.077263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T21:04:09.167203Z","caller":"traceutil/trace.go:171","msg":"trace[150730716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"237.152157ms","start":"2026-05-11T21:04:08.930044Z","end":"2026-05-11T21:04:09.167196Z","steps":["trace[150730716] 'agreement among raft nodes before linearized reading' (duration: 236.987881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:09.170898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.659622ms","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-11T21:04:09.170946Z","caller":"traceutil/trace.go:171","msg":"trace[1824322646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"240.750336ms","start":"2026-05-11T21:04:08.930185Z","end":"2026-05-11T21:04:09.170935Z","steps":["trace[1824322646] 'agreement among raft nodes before linearized reading' (duration: 240.563169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:09.171365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.618859ms","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-11T21:04:09.171395Z","caller":"traceutil/trace.go:171","msg":"trace[1496044492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"204.671561ms","start":"2026-05-11T21:04:08.966717Z","end":"2026-05-11T21:04:09.171389Z","steps":["trace[1496044492] 'agreement among raft nodes before linearized reading' (duration: 204.619239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:09.171493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.979861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T21:04:09.171519Z","caller":"traceutil/trace.go:171","msg":"trace[1185096683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"210.026313ms","start":"2026-05-11T21:04:08.961487Z","end":"2026-05-11T21:04:09.171513Z","steps":["trace[1185096683] 'agreement among raft nodes before linearized reading' (duration: 209.979812ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:10.518714Z","caller":"traceutil/trace.go:171","msg":"trace[1682301180] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"139.614678ms","start":"2026-05-11T21:04:10.379077Z","end":"2026-05-11T21:04:10.518691Z","steps":["trace[1682301180] 'read index received' (duration: 139.001286ms)","trace[1682301180] 'applied index is now lower than readState.Index' (duration: 612.462µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:04:10.520282Z","caller":"traceutil/trace.go:171","msg":"trace[216112052] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"143.078791ms","start":"2026-05-11T21:04:10.377191Z","end":"2026-05-11T21:04:10.52027Z","steps":["trace[216112052] 'process raft request' (duration: 140.924764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:10.520562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.467354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:65214"} {"level":"info","ts":"2026-05-11T21:04:10.520607Z","caller":"traceutil/trace.go:171","msg":"trace[506493988] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:581; }","duration":"141.556707ms","start":"2026-05-11T21:04:10.379039Z","end":"2026-05-11T21:04:10.520595Z","steps":["trace[506493988] 'agreement among raft nodes before linearized reading' (duration: 141.396761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:10.523664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.532788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:04:10.523702Z","caller":"traceutil/trace.go:171","msg":"trace[557197896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"119.585319ms","start":"2026-05-11T21:04:10.404107Z","end":"2026-05-11T21:04:10.523693Z","steps":["trace[557197896] 'agreement among raft nodes before linearized reading' (duration: 119.523187ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:10.523713Z","caller":"traceutil/trace.go:171","msg":"trace[2008865175] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"121.813299ms","start":"2026-05-11T21:04:10.401871Z","end":"2026-05-11T21:04:10.523684Z","steps":["trace[2008865175] 'process raft request' (duration: 121.619092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:15.71402Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121663241516535,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T21:04:15.837123Z","caller":"traceutil/trace.go:171","msg":"trace[70720380] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"623.335788ms","start":"2026-05-11T21:04:15.213737Z","end":"2026-05-11T21:04:15.837073Z","steps":["trace[70720380] 'read index received' (duration: 622.56233ms)","trace[70720380] 'applied index is now lower than readState.Index' (duration: 772.548µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:04:15.837189Z","caller":"traceutil/trace.go:171","msg":"trace[1168625663] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"630.515175ms","start":"2026-05-11T21:04:15.206619Z","end":"2026-05-11T21:04:15.837134Z","steps":["trace[1168625663] 'process raft request' (duration: 629.936944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:15.837847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:15.206576Z","time spent":"631.157909ms","remote":"127.0.0.1:57318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:04:15.837964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.205459ms","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-11T21:04:15.838089Z","caller":"traceutil/trace.go:171","msg":"trace[1731985877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"624.352624ms","start":"2026-05-11T21:04:15.213675Z","end":"2026-05-11T21:04:15.838028Z","steps":["trace[1731985877] 'agreement among raft nodes before linearized reading' (duration: 623.522484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:15.838149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:15.213649Z","time spent":"624.486599ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T21:04:15.838409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.764138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T21:04:15.838482Z","caller":"traceutil/trace.go:171","msg":"trace[1990232996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"617.850491ms","start":"2026-05-11T21:04:15.220613Z","end":"2026-05-11T21:04:15.838464Z","steps":["trace[1990232996] 'agreement among raft nodes before linearized reading' (duration: 617.503578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:15.838534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:15.220523Z","time spent":"617.990126ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T21:04:15.838955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.351623ms","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-11T21:04:15.838972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.575351ms","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-11T21:04:15.839018Z","caller":"traceutil/trace.go:171","msg":"trace[85062283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"617.399355ms","start":"2026-05-11T21:04:15.221587Z","end":"2026-05-11T21:04:15.838986Z","steps":["trace[85062283] 'agreement among raft nodes before linearized reading' (duration: 617.231258ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:15.839025Z","caller":"traceutil/trace.go:171","msg":"trace[1169144428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"617.635033ms","start":"2026-05-11T21:04:15.221364Z","end":"2026-05-11T21:04:15.838999Z","steps":["trace[1169144428] 'agreement among raft nodes before linearized reading' (duration: 617.526179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:15.839055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:15.221513Z","time spent":"617.53248ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T21:04:15.839056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:15.22135Z","time spent":"617.698196ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T21:04:15.839215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.022846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rtbf4\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-11T21:04:15.839297Z","caller":"traceutil/trace.go:171","msg":"trace[1517088702] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rtbf4; range_end:; response_count:1; response_revision:612; }","duration":"444.1402ms","start":"2026-05-11T21:04:15.395136Z","end":"2026-05-11T21:04:15.839276Z","steps":["trace[1517088702] 'agreement among raft nodes before linearized reading' (duration: 444.018466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:15.839367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:15.395111Z","time spent":"444.242444ms","remote":"127.0.0.1:57388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-rtbf4\" "} {"level":"info","ts":"2026-05-11T21:04:16.412082Z","caller":"traceutil/trace.go:171","msg":"trace[2119563406] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"565.207123ms","start":"2026-05-11T21:04:15.846842Z","end":"2026-05-11T21:04:16.412049Z","steps":["trace[2119563406] 'process raft request' (duration: 565.093618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:16.412248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:15.846824Z","time spent":"565.340957ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:04:16.412083Z","caller":"traceutil/trace.go:171","msg":"trace[1721445147] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"565.216802ms","start":"2026-05-11T21:04:15.846819Z","end":"2026-05-11T21:04:16.412036Z","steps":["trace[1721445147] 'process raft request' (duration: 564.921911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:16.412443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:15.8468Z","time spent":"565.561525ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:04:16.412624Z","caller":"traceutil/trace.go:171","msg":"trace[2002135204] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"565.103418ms","start":"2026-05-11T21:04:15.846908Z","end":"2026-05-11T21:04:16.412012Z","steps":["trace[2002135204] 'process raft request' (duration: 565.064777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:16.412734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:15.846867Z","time spent":"565.827915ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:04:16.45848Z","caller":"traceutil/trace.go:171","msg":"trace[581889674] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"395.61841ms","start":"2026-05-11T21:04:16.062827Z","end":"2026-05-11T21:04:16.458445Z","steps":["trace[581889674] 'process raft request' (duration: 395.491065ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:16.458515Z","caller":"traceutil/trace.go:171","msg":"trace[1202757323] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"568.739569ms","start":"2026-05-11T21:04:15.889745Z","end":"2026-05-11T21:04:16.458484Z","steps":["trace[1202757323] 'process raft request' (duration: 568.378866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:16.458669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:16.062802Z","time spent":"395.777786ms","remote":"127.0.0.1:57378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:04:16.458666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:15.889718Z","time spent":"568.872954ms","remote":"127.0.0.1:57732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T21:04:16.458717Z","caller":"traceutil/trace.go:171","msg":"trace[730166572] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"385.697624ms","start":"2026-05-11T21:04:16.07301Z","end":"2026-05-11T21:04:16.458708Z","steps":["trace[730166572] 'process raft request' (duration: 385.377323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:16.458792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:16.072998Z","time spent":"385.752097ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:04:16.458257Z","caller":"traceutil/trace.go:171","msg":"trace[1370566958] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"599.410269ms","start":"2026-05-11T21:04:15.858824Z","end":"2026-05-11T21:04:16.458234Z","steps":["trace[1370566958] 'process raft request' (duration: 599.16079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:16.459032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:15.858792Z","time spent":"600.215338ms","remote":"127.0.0.1:57388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:04:17.821256Z","caller":"traceutil/trace.go:171","msg":"trace[1710281749] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"930.710222ms","start":"2026-05-11T21:04:16.890507Z","end":"2026-05-11T21:04:17.821217Z","steps":["trace[1710281749] 'process raft request' (duration: 929.966196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:17.82146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:16.890479Z","time spent":"930.873848ms","remote":"127.0.0.1:57318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:04:17.849947Z","caller":"traceutil/trace.go:171","msg":"trace[1597051462] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"443.682084ms","start":"2026-05-11T21:04:17.406235Z","end":"2026-05-11T21:04:17.849917Z","steps":["trace[1597051462] 'read index received' (duration: 414.010289ms)","trace[1597051462] 'applied index is now lower than readState.Index' (duration: 29.669864ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:04:17.850083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.821269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:04:17.850156Z","caller":"traceutil/trace.go:171","msg":"trace[888092179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"443.910923ms","start":"2026-05-11T21:04:17.406228Z","end":"2026-05-11T21:04:17.850139Z","steps":["trace[888092179] 'agreement among raft nodes before linearized reading' (duration: 443.770347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:17.850193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:17.406173Z","time spent":"444.013006ms","remote":"127.0.0.1:57228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T21:04:38.778596Z","caller":"traceutil/trace.go:171","msg":"trace[220540559] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"374.527323ms","start":"2026-05-11T21:04:38.404046Z","end":"2026-05-11T21:04:38.778574Z","steps":["trace[220540559] 'read index received' (duration: 374.406719ms)","trace[220540559] 'applied index is now lower than readState.Index' (duration: 119.634µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:04:38.778683Z","caller":"traceutil/trace.go:171","msg":"trace[1498759169] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"731.459936ms","start":"2026-05-11T21:04:38.047192Z","end":"2026-05-11T21:04:38.778652Z","steps":["trace[1498759169] 'process raft request' (duration: 731.236488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:38.778857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.788153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:04:38.778913Z","caller":"traceutil/trace.go:171","msg":"trace[1593326328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"374.870396ms","start":"2026-05-11T21:04:38.404029Z","end":"2026-05-11T21:04:38.778899Z","steps":["trace[1593326328] 'agreement among raft nodes before linearized reading' (duration: 374.635597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:38.778958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:38.403961Z","time spent":"374.98887ms","remote":"127.0.0.1:57228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T21:04:38.778992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:38.04717Z","time spent":"731.684734ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:04:38.991962Z","caller":"traceutil/trace.go:171","msg":"trace[1849535298] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"213.209077ms","start":"2026-05-11T21:04:38.77873Z","end":"2026-05-11T21:04:38.99194Z","steps":["trace[1849535298] 'read index received' (duration: 211.029059ms)","trace[1849535298] 'applied index is now lower than readState.Index' (duration: 2.178908ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:04:38.992057Z","caller":"traceutil/trace.go:171","msg":"trace[1063391744] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"454.817413ms","start":"2026-05-11T21:04:38.537203Z","end":"2026-05-11T21:04:38.99202Z","steps":["trace[1063391744] 'process raft request' (duration: 452.605164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:38.99223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:38.53717Z","time spent":"454.967448ms","remote":"127.0.0.1:57448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:04:38.992428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.136118ms","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-11T21:04:38.992489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.262539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T21:04:38.992517Z","caller":"traceutil/trace.go:171","msg":"trace[309947586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"454.266073ms","start":"2026-05-11T21:04:38.538231Z","end":"2026-05-11T21:04:38.992497Z","steps":["trace[309947586] 'agreement among raft nodes before linearized reading' (duration: 453.946382ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:38.992522Z","caller":"traceutil/trace.go:171","msg":"trace[1997752863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"455.330782ms","start":"2026-05-11T21:04:38.537181Z","end":"2026-05-11T21:04:38.992511Z","steps":["trace[1997752863] 'agreement among raft nodes before linearized reading' (duration: 455.180406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:38.992566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:38.537149Z","time spent":"455.410574ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T21:04:38.992447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.900941ms","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-11T21:04:38.992603Z","caller":"traceutil/trace.go:171","msg":"trace[1109221688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"454.090528ms","start":"2026-05-11T21:04:38.538504Z","end":"2026-05-11T21:04:38.992595Z","steps":["trace[1109221688] 'agreement among raft nodes before linearized reading' (duration: 453.772106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:38.992569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:38.538219Z","time spent":"454.342226ms","remote":"127.0.0.1:57464","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-11T21:04:38.992774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.867365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T21:04:38.992633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:04:38.538491Z","time spent":"454.132449ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-11T21:04:38.992842Z","caller":"traceutil/trace.go:171","msg":"trace[446089912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"212.954138ms","start":"2026-05-11T21:04:38.779854Z","end":"2026-05-11T21:04:38.992808Z","steps":["trace[446089912] 'agreement among raft nodes before linearized reading' (duration: 212.875015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:05:14.905401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121663241517426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T21:05:15.064334Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.343149187s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T21:05:15.064737Z","caller":"traceutil/trace.go:171","msg":"trace[1688982247] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"660.229898ms","start":"2026-05-11T21:05:14.404486Z","end":"2026-05-11T21:05:15.064716Z","steps":["trace[1688982247] 'read index received' (duration: 660.027932ms)","trace[1688982247] 'applied index is now lower than readState.Index' (duration: 200.986µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:05:15.064788Z","caller":"traceutil/trace.go:171","msg":"trace[737955613] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.343714575s","start":"2026-05-11T21:05:13.721045Z","end":"2026-05-11T21:05:15.06476Z","steps":["trace[737955613] 'process raft request' (duration: 1.343493227s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:05:15.064853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.341962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T21:05:15.064932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:05:13.721012Z","time spent":"1.343844249s","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:05:15.064943Z","caller":"traceutil/trace.go:171","msg":"trace[371788167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"660.472226ms","start":"2026-05-11T21:05:14.404455Z","end":"2026-05-11T21:05:15.064927Z","steps":["trace[371788167] 'agreement among raft nodes before linearized reading' (duration: 660.325871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:05:15.065004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.339214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-11T21:05:15.065021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:05:14.404442Z","time spent":"660.570999ms","remote":"127.0.0.1:57228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T21:05:15.065046Z","caller":"traceutil/trace.go:171","msg":"trace[871084476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"130.439467ms","start":"2026-05-11T21:05:14.934597Z","end":"2026-05-11T21:05:15.065036Z","steps":["trace[871084476] 'agreement among raft nodes before linearized reading' (duration: 130.278862ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:05:37.465651Z","caller":"traceutil/trace.go:171","msg":"trace[1914665898] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"267.048914ms","start":"2026-05-11T21:05:37.198548Z","end":"2026-05-11T21:05:37.465597Z","steps":["trace[1914665898] 'process raft request' (duration: 249.810489ms)","trace[1914665898] 'compare' (duration: 17.002737ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:05:37.480489Z","caller":"traceutil/trace.go:171","msg":"trace[1922507524] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1130; }","duration":"130.481088ms","start":"2026-05-11T21:05:37.349983Z","end":"2026-05-11T21:05:37.480464Z","steps":["trace[1922507524] 'read index received' (duration: 98.364645ms)","trace[1922507524] 'applied index is now lower than readState.Index' (duration: 32.114983ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:05:37.480777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.752977ms","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-11T21:05:37.480817Z","caller":"traceutil/trace.go:171","msg":"trace[2135616730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1089; }","duration":"130.876611ms","start":"2026-05-11T21:05:37.34993Z","end":"2026-05-11T21:05:37.480806Z","steps":["trace[2135616730] 'agreement among raft nodes before linearized reading' (duration: 130.678935ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:05:39.583079Z","caller":"traceutil/trace.go:171","msg":"trace[1060622060] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"109.940688ms","start":"2026-05-11T21:05:39.4731Z","end":"2026-05-11T21:05:39.583041Z","steps":["trace[1060622060] 'process raft request' (duration: 109.761342ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:05:52.387001Z","caller":"traceutil/trace.go:171","msg":"trace[46923484] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"333.552845ms","start":"2026-05-11T21:05:52.053431Z","end":"2026-05-11T21:05:52.386983Z","steps":["trace[46923484] 'read index received' (duration: 333.444262ms)","trace[46923484] 'applied index is now lower than readState.Index' (duration: 107.523µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:05:52.387077Z","caller":"traceutil/trace.go:171","msg":"trace[97870055] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"590.150792ms","start":"2026-05-11T21:05:51.796893Z","end":"2026-05-11T21:05:52.387044Z","steps":["trace[97870055] 'process raft request' (duration: 589.977387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:05:52.387153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.711561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-11T21:05:52.387213Z","caller":"traceutil/trace.go:171","msg":"trace[1625058829] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:1191; }","duration":"333.812024ms","start":"2026-05-11T21:05:52.053388Z","end":"2026-05-11T21:05:52.3872Z","steps":["trace[1625058829] 'agreement among raft nodes before linearized reading' (duration: 333.66692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:05:52.38724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:05:52.053345Z","time spent":"333.888826ms","remote":"127.0.0.1:57260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"warn","ts":"2026-05-11T21:05:52.387242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:05:51.796873Z","time spent":"590.278417ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:05:53.978977Z","caller":"traceutil/trace.go:171","msg":"trace[1523912166] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1241; }","duration":"338.126342ms","start":"2026-05-11T21:05:53.640833Z","end":"2026-05-11T21:05:53.978959Z","steps":["trace[1523912166] 'read index received' (duration: 337.946927ms)","trace[1523912166] 'applied index is now lower than readState.Index' (duration: 178.655µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:05:53.979236Z","caller":"traceutil/trace.go:171","msg":"trace[1367152413] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"646.862928ms","start":"2026-05-11T21:05:53.332333Z","end":"2026-05-11T21:05:53.979196Z","steps":["trace[1367152413] 'process raft request' (duration: 646.519587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:05:53.979284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.430862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-11T21:05:53.979331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.054135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-11T21:05:53.979332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.147724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-11T21:05:53.979327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.700911ms","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-11T21:05:53.979367Z","caller":"traceutil/trace.go:171","msg":"trace[519105662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1194; }","duration":"322.123007ms","start":"2026-05-11T21:05:53.657234Z","end":"2026-05-11T21:05:53.979357Z","steps":["trace[519105662] 'agreement among raft nodes before linearized reading' (duration: 322.034694ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:05:53.979369Z","caller":"traceutil/trace.go:171","msg":"trace[2050632750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1194; }","duration":"320.237836ms","start":"2026-05-11T21:05:53.659123Z","end":"2026-05-11T21:05:53.97936Z","steps":["trace[2050632750] 'agreement among raft nodes before linearized reading' (duration: 320.078431ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:05:53.979364Z","caller":"traceutil/trace.go:171","msg":"trace[1144803388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1194; }","duration":"338.567007ms","start":"2026-05-11T21:05:53.640777Z","end":"2026-05-11T21:05:53.979344Z","steps":["trace[1144803388] 'agreement among raft nodes before linearized reading' (duration: 338.313219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:05:53.979395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:05:53.657221Z","time spent":"322.167848ms","remote":"127.0.0.1:57464","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-11T21:05:53.979395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:05:53.659111Z","time spent":"320.277557ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T21:05:53.979398Z","caller":"traceutil/trace.go:171","msg":"trace[1274345858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1194; }","duration":"329.821105ms","start":"2026-05-11T21:05:53.649559Z","end":"2026-05-11T21:05:53.97938Z","steps":["trace[1274345858] 'agreement among raft nodes before linearized reading' (duration: 329.596528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:05:53.979402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:05:53.332314Z","time spent":"647.008213ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:05:53.979415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:05:53.640757Z","time spent":"338.649159ms","remote":"127.0.0.1:57464","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-11T21:05:53.979444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:05:53.649541Z","time spent":"329.894978ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T21:05:54.353999Z","caller":"traceutil/trace.go:171","msg":"trace[1096549893] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"371.02825ms","start":"2026-05-11T21:05:53.982942Z","end":"2026-05-11T21:05:54.35397Z","steps":["trace[1096549893] 'process raft request' (duration: 322.111166ms)","trace[1096549893] 'compare' (duration: 48.807941ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:05:54.354163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:05:53.982926Z","time spent":"371.159685ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:05:54.355998Z","caller":"traceutil/trace.go:171","msg":"trace[1534619260] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"371.712873ms","start":"2026-05-11T21:05:53.984267Z","end":"2026-05-11T21:05:54.355979Z","steps":["trace[1534619260] 'process raft request' (duration: 371.532367ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:05:54.356037Z","caller":"traceutil/trace.go:171","msg":"trace[768042192] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"371.64877ms","start":"2026-05-11T21:05:53.984369Z","end":"2026-05-11T21:05:54.356018Z","steps":["trace[768042192] 'process raft request' (duration: 371.525236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:05:54.356118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:05:53.984243Z","time spent":"371.810856ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:05:54.356117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:05:53.984361Z","time spent":"371.715883ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:05:54.356225Z","caller":"traceutil/trace.go:171","msg":"trace[467570156] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"371.654811ms","start":"2026-05-11T21:05:53.984562Z","end":"2026-05-11T21:05:54.356217Z","steps":["trace[467570156] 'process raft request' (duration: 371.377342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:05:54.356293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:05:53.984551Z","time spent":"371.691753ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:06:14.590895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.893214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T21:06:14.590994Z","caller":"traceutil/trace.go:171","msg":"trace[249208768] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"422.724873ms","start":"2026-05-11T21:06:14.168252Z","end":"2026-05-11T21:06:14.590977Z","steps":["trace[249208768] 'read index received' (duration: 226.598161ms)","trace[249208768] 'applied index is now lower than readState.Index' (duration: 196.125082ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:06:14.591191Z","caller":"traceutil/trace.go:171","msg":"trace[1839712942] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"472.257768ms","start":"2026-05-11T21:06:14.118864Z","end":"2026-05-11T21:06:14.591122Z","steps":["trace[1839712942] 'process raft request' (duration: 276.050913ms)","trace[1839712942] 'compare' (duration: 195.732939ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:06:14.591395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.141357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-11T21:06:14.591431Z","caller":"traceutil/trace.go:171","msg":"trace[1558940163] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1295; }","duration":"423.216189ms","start":"2026-05-11T21:06:14.168207Z","end":"2026-05-11T21:06:14.591423Z","steps":["trace[1558940163] 'agreement among raft nodes before linearized reading' (duration: 423.030343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:14.591405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:14.118845Z","time spent":"472.457623ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:06:14.591535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:14.168193Z","time spent":"423.329142ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T21:06:14.591467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.243198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T21:06:14.59168Z","caller":"traceutil/trace.go:171","msg":"trace[1799210584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1295; }","duration":"101.487666ms","start":"2026-05-11T21:06:14.490176Z","end":"2026-05-11T21:06:14.591664Z","steps":["trace[1799210584] 'agreement among raft nodes before linearized reading' (duration: 101.148955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:15.021539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.788682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T21:06:15.021639Z","caller":"traceutil/trace.go:171","msg":"trace[516451194] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"425.164413ms","start":"2026-05-11T21:06:14.596456Z","end":"2026-05-11T21:06:15.021621Z","steps":["trace[516451194] 'process raft request' (duration: 215.213457ms)","trace[516451194] 'compare' (duration: 209.665047ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:06:15.021818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:14.596433Z","time spent":"425.350039ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:06:15.05669Z","caller":"traceutil/trace.go:171","msg":"trace[1340254012] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"460.027025ms","start":"2026-05-11T21:06:14.596638Z","end":"2026-05-11T21:06:15.056665Z","steps":["trace[1340254012] 'process raft request' (duration: 459.949913ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:15.056883Z","caller":"traceutil/trace.go:171","msg":"trace[380317476] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1348; }","duration":"221.715936ms","start":"2026-05-11T21:06:14.835157Z","end":"2026-05-11T21:06:15.056873Z","steps":["trace[380317476] 'read index received' (duration: 221.20463ms)","trace[380317476] 'applied index is now lower than readState.Index' (duration: 510.656µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:06:15.056907Z","caller":"traceutil/trace.go:171","msg":"trace[1475500751] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"460.216292ms","start":"2026-05-11T21:06:14.596675Z","end":"2026-05-11T21:06:15.056891Z","steps":["trace[1475500751] 'process raft request' (duration: 459.953833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:15.056969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:14.596617Z","time spent":"460.225952ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:06:15.056996Z","caller":"traceutil/trace.go:171","msg":"trace[519779734] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"460.299294ms","start":"2026-05-11T21:06:14.59668Z","end":"2026-05-11T21:06:15.05698Z","steps":["trace[519779734] 'process raft request' (duration: 460.117489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:15.057139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:14.596662Z","time spent":"460.409937ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:06:15.056704Z","caller":"traceutil/trace.go:171","msg":"trace[247048226] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"460.126768ms","start":"2026-05-11T21:06:14.596539Z","end":"2026-05-11T21:06:15.056666Z","steps":["trace[247048226] 'process raft request' (duration: 459.87748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:15.057254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:14.596517Z","time spent":"460.706407ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:06:15.056722Z","caller":"traceutil/trace.go:171","msg":"trace[2003493604] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"460.066986ms","start":"2026-05-11T21:06:14.596625Z","end":"2026-05-11T21:06:15.056692Z","steps":["trace[2003493604] 'process raft request' (duration: 459.925162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:15.057007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.84833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:06:15.057355Z","caller":"traceutil/trace.go:171","msg":"trace[990855099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"222.265893ms","start":"2026-05-11T21:06:14.835079Z","end":"2026-05-11T21:06:15.057345Z","steps":["trace[990855099] 'agreement among raft nodes before linearized reading' (duration: 221.84731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:15.057345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:14.596617Z","time spent":"460.698787ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:06:15.057011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:14.596655Z","time spent":"460.292133ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:06:23.289607Z","caller":"traceutil/trace.go:171","msg":"trace[853054896] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"201.481994ms","start":"2026-05-11T21:06:23.088097Z","end":"2026-05-11T21:06:23.289579Z","steps":["trace[853054896] 'read index received' (duration: 201.294788ms)","trace[853054896] 'applied index is now lower than readState.Index' (duration: 186.026µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:06:23.289689Z","caller":"traceutil/trace.go:171","msg":"trace[285898431] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"231.50945ms","start":"2026-05-11T21:06:23.058145Z","end":"2026-05-11T21:06:23.289654Z","steps":["trace[285898431] 'process raft request' (duration: 231.21468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:23.289894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.880304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-11T21:06:23.289937Z","caller":"traceutil/trace.go:171","msg":"trace[999903006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1356; }","duration":"195.966997ms","start":"2026-05-11T21:06:23.09396Z","end":"2026-05-11T21:06:23.289927Z","steps":["trace[999903006] 'agreement among raft nodes before linearized reading' (duration: 195.77161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:23.289965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.763228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-11T21:06:23.289998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.7418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-11T21:06:23.290039Z","caller":"traceutil/trace.go:171","msg":"trace[1571185384] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1356; }","duration":"194.905933ms","start":"2026-05-11T21:06:23.095116Z","end":"2026-05-11T21:06:23.290022Z","steps":["trace[1571185384] 'agreement among raft nodes before linearized reading' (duration: 194.661145ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:23.290065Z","caller":"traceutil/trace.go:171","msg":"trace[1885704527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1356; }","duration":"195.830152ms","start":"2026-05-11T21:06:23.094215Z","end":"2026-05-11T21:06:23.290046Z","steps":["trace[1885704527] 'agreement among raft nodes before linearized reading' (duration: 195.591065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:23.290518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.394673ms","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-11T21:06:23.290695Z","caller":"traceutil/trace.go:171","msg":"trace[1273480904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1356; }","duration":"202.526908ms","start":"2026-05-11T21:06:23.088056Z","end":"2026-05-11T21:06:23.290583Z","steps":["trace[1273480904] 'agreement among raft nodes before linearized reading' (duration: 201.632889ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:23.428723Z","caller":"traceutil/trace.go:171","msg":"trace[229383630] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"134.117077ms","start":"2026-05-11T21:06:23.294579Z","end":"2026-05-11T21:06:23.428696Z","steps":["trace[229383630] 'process raft request' (duration: 130.944075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:24.116166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.203312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T21:06:24.116358Z","caller":"traceutil/trace.go:171","msg":"trace[61264861] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"821.072935ms","start":"2026-05-11T21:06:23.295267Z","end":"2026-05-11T21:06:24.11634Z","steps":["trace[61264861] 'process raft request' (duration: 820.984362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:24.116419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:23.295258Z","time spent":"821.131657ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:06:24.116582Z","caller":"traceutil/trace.go:171","msg":"trace[663231664] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"821.508139ms","start":"2026-05-11T21:06:23.295045Z","end":"2026-05-11T21:06:24.116553Z","steps":["trace[663231664] 'process raft request' (duration: 341.842432ms)","trace[663231664] 'compare' (duration: 478.735687ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:06:24.116675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:23.295027Z","time spent":"821.603692ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:06:24.118104Z","caller":"traceutil/trace.go:171","msg":"trace[1310963813] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"821.150657ms","start":"2026-05-11T21:06:23.296931Z","end":"2026-05-11T21:06:24.118082Z","steps":["trace[1310963813] 'process raft request' (duration: 819.37698ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:24.118012Z","caller":"traceutil/trace.go:171","msg":"trace[1001823736] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"198.379834ms","start":"2026-05-11T21:06:23.919606Z","end":"2026-05-11T21:06:24.117986Z","steps":["trace[1001823736] 'process raft request' (duration: 198.295161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:24.118384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:23.296924Z","time spent":"821.284291ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:06:24.118669Z","caller":"traceutil/trace.go:171","msg":"trace[900698253] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1410; }","duration":"713.520793ms","start":"2026-05-11T21:06:23.405132Z","end":"2026-05-11T21:06:24.118653Z","steps":["trace[900698253] 'read index received' (duration: 20.399056ms)","trace[900698253] 'applied index is now lower than readState.Index' (duration: 693.120207ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:06:24.118822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.893035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-11T21:06:24.118855Z","caller":"traceutil/trace.go:171","msg":"trace[483074990] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1361; }","duration":"658.967507ms","start":"2026-05-11T21:06:23.45988Z","end":"2026-05-11T21:06:24.118847Z","steps":["trace[483074990] 'agreement among raft nodes before linearized reading' (duration: 658.876524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:24.118881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:23.459851Z","time spent":"659.024809ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-11T21:06:24.11894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.757491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:06:24.119048Z","caller":"traceutil/trace.go:171","msg":"trace[2075356733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"713.935597ms","start":"2026-05-11T21:06:23.405099Z","end":"2026-05-11T21:06:24.119034Z","steps":["trace[2075356733] 'agreement among raft nodes before linearized reading' (duration: 713.762261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:24.119214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:23.405086Z","time spent":"714.017349ms","remote":"127.0.0.1:57228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T21:09:32.453176Z","caller":"traceutil/trace.go:171","msg":"trace[218953901] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"229.364161ms","start":"2026-05-11T21:09:32.223791Z","end":"2026-05-11T21:09:32.453155Z","steps":["trace[218953901] 'process raft request' (duration: 211.171585ms)","trace[218953901] 'compare' (duration: 17.784453ms)"],"step_count":2}