{"level":"warn","ts":"2026-06-10T08:55:22.148773Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T08:55:22.148857Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.111: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.19.213.111:2380","--initial-cluster=instance=https://199.19.213.111:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.111:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.111: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-06-10T08:55:22.148916Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T08:55:22.148984Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.111:2380"]} {"level":"info","ts":"2026-06-10T08:55:22.149007Z","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-06-10T08:55:22.149596Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"]} {"level":"info","ts":"2026-06-10T08:55:22.149667Z","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.19.213.111:2380"],"listen-peer-urls":["https://199.19.213.111:2380"],"advertise-client-urls":["https://199.19.213.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.111: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-06-10T08:55:22.152784Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.676012ms"} {"level":"info","ts":"2026-06-10T08:55:22.15767Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4edbe0dc99dceaa8","cluster-id":"9f725a00b80fcc82"} {"level":"info","ts":"2026-06-10T08:55:22.157768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=()"} {"level":"info","ts":"2026-06-10T08:55:22.1578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became follower at term 0"} {"level":"info","ts":"2026-06-10T08:55:22.157816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4edbe0dc99dceaa8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-10T08:55:22.157833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became follower at term 1"} {"level":"info","ts":"2026-06-10T08:55:22.157909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=(5682382592936831656)"} {"level":"warn","ts":"2026-06-10T08:55:22.162615Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-10T08:55:22.164088Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-10T08:55:22.165116Z","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-06-10T08:55:22.167985Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4edbe0dc99dceaa8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-10T08:55:22.169124Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4edbe0dc99dceaa8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-10T08:55:22.169273Z","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-06-10T08:55:22.169409Z","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-06-10T08:55:22.16942Z","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-06-10T08:55:22.170256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=(5682382592936831656)"} {"level":"info","ts":"2026-06-10T08:55:22.170473Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9f725a00b80fcc82","local-member-id":"4edbe0dc99dceaa8","added-peer-id":"4edbe0dc99dceaa8","added-peer-peer-urls":["https://199.19.213.111:2380"]} {"level":"info","ts":"2026-06-10T08:55:22.172351Z","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-06-10T08:55:22.172607Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4edbe0dc99dceaa8","initial-advertise-peer-urls":["https://199.19.213.111:2380"],"listen-peer-urls":["https://199.19.213.111:2380"],"advertise-client-urls":["https://199.19.213.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-10T08:55:22.172642Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-10T08:55:22.172818Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.111:2380"} {"level":"info","ts":"2026-06-10T08:55:22.172835Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.111:2380"} {"level":"info","ts":"2026-06-10T08:55:22.359305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 is starting a new election at term 1"} {"level":"info","ts":"2026-06-10T08:55:22.359386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-10T08:55:22.359441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 received MsgPreVoteResp from 4edbe0dc99dceaa8 at term 1"} {"level":"info","ts":"2026-06-10T08:55:22.359463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became candidate at term 2"} {"level":"info","ts":"2026-06-10T08:55:22.359474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 received MsgVoteResp from 4edbe0dc99dceaa8 at term 2"} {"level":"info","ts":"2026-06-10T08:55:22.359509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became leader at term 2"} {"level":"info","ts":"2026-06-10T08:55:22.359523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4edbe0dc99dceaa8 elected leader 4edbe0dc99dceaa8 at term 2"} {"level":"info","ts":"2026-06-10T08:55:22.360728Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4edbe0dc99dceaa8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.111:2379]}","request-path":"/0/members/4edbe0dc99dceaa8/attributes","cluster-id":"9f725a00b80fcc82","publish-timeout":"7s"} {"level":"info","ts":"2026-06-10T08:55:22.360793Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T08:55:22.360857Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T08:55:22.360961Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-10T08:55:22.360983Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-10T08:55:22.363248Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.111:2379"} {"level":"info","ts":"2026-06-10T08:55:22.363255Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-10T08:55:22.360752Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:55:22.368585Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9f725a00b80fcc82","local-member-id":"4edbe0dc99dceaa8","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:55:22.368674Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:55:22.368722Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:55:40.344504Z","caller":"traceutil/trace.go:171","msg":"trace[1941350657] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"319.094159ms","start":"2026-06-10T08:55:40.025348Z","end":"2026-06-10T08:55:40.344442Z","steps":["trace[1941350657] 'read index received' (duration: 318.923945ms)","trace[1941350657] 'applied index is now lower than readState.Index' (duration: 169.194µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:55:40.344668Z","caller":"traceutil/trace.go:171","msg":"trace[606449840] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"322.617408ms","start":"2026-06-10T08:55:40.022032Z","end":"2026-06-10T08:55:40.344649Z","steps":["trace[606449840] 'process raft request' (duration: 322.21565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:55:40.344669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.298842ms","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-06-10T08:55:40.344739Z","caller":"traceutil/trace.go:171","msg":"trace[520125612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:418; }","duration":"319.404465ms","start":"2026-06-10T08:55:40.025323Z","end":"2026-06-10T08:55:40.344727Z","steps":["trace[520125612] 'agreement among raft nodes before linearized reading' (duration: 319.283583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:55:40.344792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:55:40.025316Z","time spent":"319.452816ms","remote":"127.0.0.1:43552","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-06-10T08:55:40.345228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:55:40.022014Z","time spent":"322.718819ms","remote":"127.0.0.1:43446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:55:46.363004Z","caller":"traceutil/trace.go:171","msg":"trace[1929801314] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"332.982162ms","start":"2026-06-10T08:55:46.029994Z","end":"2026-06-10T08:55:46.362976Z","steps":["trace[1929801314] 'process raft request' (duration: 332.825929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:55:46.363366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:55:46.029956Z","time spent":"333.329768ms","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:55:48.342725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16908939282906690002,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T08:55:48.411358Z","caller":"traceutil/trace.go:171","msg":"trace[1417984942] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"569.06471ms","start":"2026-06-10T08:55:47.842252Z","end":"2026-06-10T08:55:48.411317Z","steps":["trace[1417984942] 'read index received' (duration: 568.848826ms)","trace[1417984942] 'applied index is now lower than readState.Index' (duration: 214.384µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:55:48.411437Z","caller":"traceutil/trace.go:171","msg":"trace[1167065910] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"673.576062ms","start":"2026-06-10T08:55:47.737839Z","end":"2026-06-10T08:55:48.411415Z","steps":["trace[1167065910] 'process raft request' (duration: 673.268026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:55:48.411557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:55:47.737824Z","time spent":"673.662825ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:55:48.411587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.719835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-10T08:55:48.411601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.312915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-10T08:55:48.411723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.376845ms","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-06-10T08:55:48.411812Z","caller":"traceutil/trace.go:171","msg":"trace[2105923596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:438; }","duration":"430.483387ms","start":"2026-06-10T08:55:47.981299Z","end":"2026-06-10T08:55:48.411783Z","steps":["trace[2105923596] 'agreement among raft nodes before linearized reading' (duration: 430.337594ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:55:48.411778Z","caller":"traceutil/trace.go:171","msg":"trace[716556504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:438; }","duration":"569.58583ms","start":"2026-06-10T08:55:47.842168Z","end":"2026-06-10T08:55:48.411754Z","steps":["trace[716556504] 'agreement among raft nodes before linearized reading' (duration: 569.297834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:55:48.411862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:55:47.981284Z","time spent":"430.564198ms","remote":"127.0.0.1:43552","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-06-10T08:55:48.411883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:55:47.842145Z","time spent":"569.724553ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T08:55:48.411651Z","caller":"traceutil/trace.go:171","msg":"trace[1204149082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:438; }","duration":"535.843357ms","start":"2026-06-10T08:55:47.875791Z","end":"2026-06-10T08:55:48.411634Z","steps":["trace[1204149082] 'agreement among raft nodes before linearized reading' (duration: 535.722015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:55:48.411961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:55:47.875767Z","time spent":"536.176504ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-10T08:55:48.920375Z","caller":"traceutil/trace.go:171","msg":"trace[1614532781] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"503.789236ms","start":"2026-06-10T08:55:48.416562Z","end":"2026-06-10T08:55:48.920352Z","steps":["trace[1614532781] 'process raft request' (duration: 502.744316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:55:48.920477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:55:48.416535Z","time spent":"503.888949ms","remote":"127.0.0.1:43552","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-06-10T08:55:48.923885Z","caller":"traceutil/trace.go:171","msg":"trace[263383047] linearizableReadLoop","detail":"{readStateIndex:457; appliedIndex:454; }","duration":"503.305728ms","start":"2026-06-10T08:55:48.420562Z","end":"2026-06-10T08:55:48.923868Z","steps":["trace[263383047] 'read index received' (duration: 498.741129ms)","trace[263383047] 'applied index is now lower than readState.Index' (duration: 4.563959ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:55:48.923994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.39885ms","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-06-10T08:55:48.924039Z","caller":"traceutil/trace.go:171","msg":"trace[1189129384] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:442; }","duration":"503.490851ms","start":"2026-06-10T08:55:48.420537Z","end":"2026-06-10T08:55:48.924027Z","steps":["trace[1189129384] 'agreement among raft nodes before linearized reading' (duration: 503.3987ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:55:48.924013Z","caller":"traceutil/trace.go:171","msg":"trace[817358365] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"507.073931ms","start":"2026-06-10T08:55:48.416925Z","end":"2026-06-10T08:55:48.923999Z","steps":["trace[817358365] 'process raft request' (duration: 506.810056ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:55:48.924048Z","caller":"traceutil/trace.go:171","msg":"trace[1633834661] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"507.052911ms","start":"2026-06-10T08:55:48.416954Z","end":"2026-06-10T08:55:48.924007Z","steps":["trace[1633834661] 'process raft request' (duration: 506.845007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:55:48.92408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:55:48.420528Z","time spent":"503.540842ms","remote":"127.0.0.1:43552","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-06-10T08:55:48.924103Z","caller":"traceutil/trace.go:171","msg":"trace[455021752] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"498.654536ms","start":"2026-06-10T08:55:48.425414Z","end":"2026-06-10T08:55:48.924069Z","steps":["trace[455021752] 'process raft request' (duration: 498.412761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:55:48.924172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:55:48.416909Z","time spent":"507.188124ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:55:48.924184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:55:48.416931Z","time spent":"507.185283ms","remote":"127.0.0.1:43552","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-06-10T08:55:48.924216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:55:48.425395Z","time spent":"498.762918ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:55:55.604625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:55:55.288585Z","time spent":"316.036408ms","remote":"127.0.0.1:43278","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-10T08:56:01.129355Z","caller":"traceutil/trace.go:171","msg":"trace[627777356] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:549; }","duration":"103.726478ms","start":"2026-06-10T08:56:01.025607Z","end":"2026-06-10T08:56:01.129333Z","steps":["trace[627777356] 'read index received' (duration: 14.177329ms)","trace[627777356] 'applied index is now lower than readState.Index' (duration: 89.548169ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:56:01.129409Z","caller":"traceutil/trace.go:171","msg":"trace[747204031] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"142.005719ms","start":"2026-06-10T08:56:00.987373Z","end":"2026-06-10T08:56:01.129378Z","steps":["trace[747204031] 'process raft request' (duration: 141.55707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:01.129551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.923481ms","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-06-10T08:56:01.129627Z","caller":"traceutil/trace.go:171","msg":"trace[638645851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"104.037953ms","start":"2026-06-10T08:56:01.025572Z","end":"2026-06-10T08:56:01.12961Z","steps":["trace[638645851] 'agreement among raft nodes before linearized reading' (duration: 103.88182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:02.043937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16908939282906690187,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T08:56:02.058227Z","caller":"traceutil/trace.go:171","msg":"trace[606619946] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"646.560173ms","start":"2026-06-10T08:56:01.411645Z","end":"2026-06-10T08:56:02.058206Z","steps":["trace[606619946] 'process raft request' (duration: 646.44175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:02.058374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:01.411626Z","time spent":"646.662124ms","remote":"127.0.0.1:43298","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-06-10T08:56:02.067782Z","caller":"traceutil/trace.go:171","msg":"trace[369552111] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"645.41414ms","start":"2026-06-10T08:56:01.422354Z","end":"2026-06-10T08:56:02.067768Z","steps":["trace[369552111] 'process raft request' (duration: 645.154735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:02.067849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:01.422333Z","time spent":"645.481681ms","remote":"127.0.0.1:43298","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-06-10T08:56:02.067867Z","caller":"traceutil/trace.go:171","msg":"trace[618243398] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"645.404058ms","start":"2026-06-10T08:56:01.422426Z","end":"2026-06-10T08:56:02.06783Z","steps":["trace[618243398] 'process raft request' (duration: 645.222194ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:56:02.067939Z","caller":"traceutil/trace.go:171","msg":"trace[1766990635] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"640.104026ms","start":"2026-06-10T08:56:01.427803Z","end":"2026-06-10T08:56:02.067907Z","steps":["trace[1766990635] 'process raft request' (duration: 639.931702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:02.068022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:01.422421Z","time spent":"645.525812ms","remote":"127.0.0.1:43298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:56:02.068157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:01.427781Z","time spent":"640.262449ms","remote":"127.0.0.1:43298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:56:02.069028Z","caller":"traceutil/trace.go:171","msg":"trace[1538860964] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"640.351089ms","start":"2026-06-10T08:56:01.428664Z","end":"2026-06-10T08:56:02.069015Z","steps":["trace[1538860964] 'process raft request' (duration: 639.123735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:02.069131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:01.428653Z","time spent":"640.446341ms","remote":"127.0.0.1:43298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:56:02.069166Z","caller":"traceutil/trace.go:171","msg":"trace[776480542] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"634.950813ms","start":"2026-06-10T08:56:01.434203Z","end":"2026-06-10T08:56:02.069153Z","steps":["trace[776480542] 'process raft request' (duration: 634.656577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:02.069223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:01.434186Z","time spent":"635.012965ms","remote":"127.0.0.1:43298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:56:02.070033Z","caller":"traceutil/trace.go:171","msg":"trace[643789276] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:553; }","duration":"526.770108ms","start":"2026-06-10T08:56:01.543249Z","end":"2026-06-10T08:56:02.070019Z","steps":["trace[643789276] 'read index received' (duration: 514.879365ms)","trace[643789276] 'applied index is now lower than readState.Index' (duration: 11.889893ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:56:02.070157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.872541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:56:02.070198Z","caller":"traceutil/trace.go:171","msg":"trace[111838776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"526.950322ms","start":"2026-06-10T08:56:01.543233Z","end":"2026-06-10T08:56:02.070183Z","steps":["trace[111838776] 'agreement among raft nodes before linearized reading' (duration: 526.84031ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:02.070261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:01.543173Z","time spent":"527.051044ms","remote":"127.0.0.1:43226","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-06-10T08:56:02.070479Z","caller":"traceutil/trace.go:171","msg":"trace[1705922921] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"629.210361ms","start":"2026-06-10T08:56:01.441259Z","end":"2026-06-10T08:56:02.07047Z","steps":["trace[1705922921] 'process raft request' (duration: 627.680841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:02.070533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:01.441241Z","time spent":"629.266561ms","remote":"127.0.0.1:43298","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-06-10T08:56:02.070622Z","caller":"traceutil/trace.go:171","msg":"trace[134048527] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"586.329268ms","start":"2026-06-10T08:56:01.484273Z","end":"2026-06-10T08:56:02.070602Z","steps":["trace[134048527] 'process raft request' (duration: 585.599994ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:56:02.07065Z","caller":"traceutil/trace.go:171","msg":"trace[1557606074] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"486.762542ms","start":"2026-06-10T08:56:01.583878Z","end":"2026-06-10T08:56:02.070641Z","steps":["trace[1557606074] 'process raft request' (duration: 486.090779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:02.07068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:01.484255Z","time spent":"586.399299ms","remote":"127.0.0.1:43298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:56:02.070696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:01.583851Z","time spent":"486.826125ms","remote":"127.0.0.1:43298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:56:02.070793Z","caller":"traceutil/trace.go:171","msg":"trace[693015247] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"624.632202ms","start":"2026-06-10T08:56:01.446152Z","end":"2026-06-10T08:56:02.070784Z","steps":["trace[693015247] 'process raft request' (duration: 623.460018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:02.070827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:01.44614Z","time spent":"624.672062ms","remote":"127.0.0.1:43298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:56:02.070884Z","caller":"traceutil/trace.go:171","msg":"trace[1766122561] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"609.549875ms","start":"2026-06-10T08:56:01.461329Z","end":"2026-06-10T08:56:02.070879Z","steps":["trace[1766122561] 'process raft request' (duration: 608.343762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:02.070952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:01.461312Z","time spent":"609.628057ms","remote":"127.0.0.1:43298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:56:02.071002Z","caller":"traceutil/trace.go:171","msg":"trace[1572689499] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"609.500625ms","start":"2026-06-10T08:56:01.461496Z","end":"2026-06-10T08:56:02.070997Z","steps":["trace[1572689499] 'process raft request' (duration: 608.26781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:02.071028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:01.46147Z","time spent":"609.547735ms","remote":"127.0.0.1:43298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:56:08.385233Z","caller":"traceutil/trace.go:171","msg":"trace[1688526277] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"595.583351ms","start":"2026-06-10T08:56:07.789607Z","end":"2026-06-10T08:56:08.385191Z","steps":["trace[1688526277] 'process raft request' (duration: 593.887447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:08.385426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:07.789581Z","time spent":"595.737394ms","remote":"127.0.0.1:43350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:56:08.936617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.482513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:56:08.936817Z","caller":"traceutil/trace.go:171","msg":"trace[1463639942] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"462.618768ms","start":"2026-06-10T08:56:08.47418Z","end":"2026-06-10T08:56:08.936799Z","steps":["trace[1463639942] 'read index received' (duration: 16.80025ms)","trace[1463639942] 'applied index is now lower than readState.Index' (duration: 445.816698ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:56:08.936904Z","caller":"traceutil/trace.go:171","msg":"trace[1657679856] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"493.312151ms","start":"2026-06-10T08:56:08.44354Z","end":"2026-06-10T08:56:08.936852Z","steps":["trace[1657679856] 'process raft request' (duration: 47.528034ms)","trace[1657679856] 'compare' (duration: 444.274228ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:56:08.93702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.831093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:56:08.937061Z","caller":"traceutil/trace.go:171","msg":"trace[1308433268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"462.918505ms","start":"2026-06-10T08:56:08.474132Z","end":"2026-06-10T08:56:08.937051Z","steps":["trace[1308433268] 'agreement among raft nodes before linearized reading' (duration: 462.780282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:08.937094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:08.443514Z","time spent":"493.496105ms","remote":"127.0.0.1:43462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:56:08.937134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:08.474108Z","time spent":"463.017027ms","remote":"127.0.0.1:43236","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-06-10T08:56:09.626425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.250351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-10T08:56:09.626546Z","caller":"traceutil/trace.go:171","msg":"trace[2027972557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"546.472796ms","start":"2026-06-10T08:56:09.080051Z","end":"2026-06-10T08:56:09.626524Z","steps":["trace[2027972557] 'range keys from in-memory index tree' (duration: 546.005917ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:56:09.626571Z","caller":"traceutil/trace.go:171","msg":"trace[878911125] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"540.0538ms","start":"2026-06-10T08:56:09.086492Z","end":"2026-06-10T08:56:09.626546Z","steps":["trace[878911125] 'process raft request' (duration: 527.561185ms)","trace[878911125] 'compare' (duration: 12.041887ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:56:09.626597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:09.080023Z","time spent":"546.564727ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T08:56:09.626684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:09.086475Z","time spent":"540.151112ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:56:09.627033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.455024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-10T08:56:09.627064Z","caller":"traceutil/trace.go:171","msg":"trace[385048515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"465.486555ms","start":"2026-06-10T08:56:09.161569Z","end":"2026-06-10T08:56:09.627055Z","steps":["trace[385048515] 'agreement among raft nodes before linearized reading' (duration: 465.384363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:09.627084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:09.161486Z","time spent":"465.593296ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T08:56:09.627039Z","caller":"traceutil/trace.go:171","msg":"trace[133681184] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"464.896393ms","start":"2026-06-10T08:56:09.161586Z","end":"2026-06-10T08:56:09.626483Z","steps":["trace[133681184] 'read index received' (duration: 452.606741ms)","trace[133681184] 'applied index is now lower than readState.Index' (duration: 12.288832ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:56:11.251363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247758403s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:56:11.2516Z","caller":"traceutil/trace.go:171","msg":"trace[73502257] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.885647215s","start":"2026-06-10T08:56:09.365926Z","end":"2026-06-10T08:56:11.251574Z","steps":["trace[73502257] 'process raft request' (duration: 637.617146ms)","trace[73502257] 'compare' (duration: 1.247458357s)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:56:11.251746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:09.365888Z","time spent":"1.885780248s","remote":"127.0.0.1:43350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:56:11.251741Z","caller":"traceutil/trace.go:171","msg":"trace[1538868422] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"1.624340501s","start":"2026-06-10T08:56:09.62737Z","end":"2026-06-10T08:56:11.251711Z","steps":["trace[1538868422] 'read index received' (duration: 375.485906ms)","trace[1538868422] 'applied index is now lower than readState.Index' (duration: 1.248852145s)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:56:11.251835Z","caller":"traceutil/trace.go:171","msg":"trace[1801792199] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.620331602s","start":"2026-06-10T08:56:09.631489Z","end":"2026-06-10T08:56:11.251821Z","steps":["trace[1801792199] 'process raft request' (duration: 1.620127928s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:11.251922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:09.631457Z","time spent":"1.620440514s","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:56:11.251992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.087632782s","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-06-10T08:56:11.252102Z","caller":"traceutil/trace.go:171","msg":"trace[747268229] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.620762202s","start":"2026-06-10T08:56:09.631261Z","end":"2026-06-10T08:56:11.252024Z","steps":["trace[747268229] 'process raft request' (duration: 1.620266112s)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:56:11.252132Z","caller":"traceutil/trace.go:171","msg":"trace[394173610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"2.087728825s","start":"2026-06-10T08:56:09.164309Z","end":"2026-06-10T08:56:11.252038Z","steps":["trace[394173610] 'agreement among raft nodes before linearized reading' (duration: 2.08749029s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:11.252133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.780248103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:56:11.252191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:09.164295Z","time spent":"2.087881817s","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T08:56:11.252212Z","caller":"traceutil/trace.go:171","msg":"trace[167674759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"1.780363526s","start":"2026-06-10T08:56:09.47183Z","end":"2026-06-10T08:56:11.252193Z","steps":["trace[167674759] 'agreement among raft nodes before linearized reading' (duration: 1.780213823s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:11.252244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:09.63124Z","time spent":"1.620935014s","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:56:11.252259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:09.471808Z","time spent":"1.780441877s","remote":"127.0.0.1:43226","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-06-10T08:56:11.252627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.086970989s","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-06-10T08:56:11.252747Z","caller":"traceutil/trace.go:171","msg":"trace[1000060479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"2.087144973s","start":"2026-06-10T08:56:09.165571Z","end":"2026-06-10T08:56:11.252716Z","steps":["trace[1000060479] 'agreement among raft nodes before linearized reading' (duration: 2.086544201s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:11.252808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:09.165551Z","time spent":"2.087246295s","remote":"127.0.0.1:43552","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":"info","ts":"2026-06-10T08:56:11.370067Z","caller":"traceutil/trace.go:171","msg":"trace[711119680] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"116.554149ms","start":"2026-06-10T08:56:11.253476Z","end":"2026-06-10T08:56:11.37003Z","steps":["trace[711119680] 'read index received' (duration: 42.175478ms)","trace[711119680] 'applied index is now lower than readState.Index' (duration: 74.375951ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:56:11.370342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.717682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:56:11.370388Z","caller":"traceutil/trace.go:171","msg":"trace[381749902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"116.946028ms","start":"2026-06-10T08:56:11.253426Z","end":"2026-06-10T08:56:11.370372Z","steps":["trace[381749902] 'agreement among raft nodes before linearized reading' (duration: 116.716702ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:56:11.370362Z","caller":"traceutil/trace.go:171","msg":"trace[178859224] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"113.007769ms","start":"2026-06-10T08:56:11.257319Z","end":"2026-06-10T08:56:11.370327Z","steps":["trace[178859224] 'process raft request' (duration: 112.642112ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:56:11.370769Z","caller":"traceutil/trace.go:171","msg":"trace[617741332] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"113.165503ms","start":"2026-06-10T08:56:11.257572Z","end":"2026-06-10T08:56:11.370737Z","steps":["trace[617741332] 'process raft request' (duration: 112.678253ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:56:12.951386Z","caller":"traceutil/trace.go:171","msg":"trace[1794721760] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"128.120997ms","start":"2026-06-10T08:56:12.823234Z","end":"2026-06-10T08:56:12.951355Z","steps":["trace[1794721760] 'process raft request' (duration: 127.374052ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:56:23.656297Z","caller":"traceutil/trace.go:171","msg":"trace[2097837373] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"224.698074ms","start":"2026-06-10T08:56:23.431567Z","end":"2026-06-10T08:56:23.656265Z","steps":["trace[2097837373] 'read index received' (duration: 224.50002ms)","trace[2097837373] 'applied index is now lower than readState.Index' (duration: 197.304µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:56:23.656408Z","caller":"traceutil/trace.go:171","msg":"trace[1168886836] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"345.12061ms","start":"2026-06-10T08:56:23.31124Z","end":"2026-06-10T08:56:23.656361Z","steps":["trace[1168886836] 'process raft request' (duration: 344.882675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:23.656523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.113103ms","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-06-10T08:56:23.656558Z","caller":"traceutil/trace.go:171","msg":"trace[201500246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"223.199255ms","start":"2026-06-10T08:56:23.433349Z","end":"2026-06-10T08:56:23.656548Z","steps":["trace[201500246] 'agreement among raft nodes before linearized reading' (duration: 223.061632ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:23.656547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.140947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:56:23.65659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:23.311217Z","time spent":"345.295793ms","remote":"127.0.0.1:43552","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-06-10T08:56:23.656611Z","caller":"traceutil/trace.go:171","msg":"trace[225093391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"185.237799ms","start":"2026-06-10T08:56:23.471353Z","end":"2026-06-10T08:56:23.656591Z","steps":["trace[225093391] 'agreement among raft nodes before linearized reading' (duration: 185.136016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:23.657035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.47304ms","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-06-10T08:56:23.657084Z","caller":"traceutil/trace.go:171","msg":"trace[1487168165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"225.51793ms","start":"2026-06-10T08:56:23.431552Z","end":"2026-06-10T08:56:23.65707Z","steps":["trace[1487168165] 'agreement among raft nodes before linearized reading' (duration: 224.836106ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:56:27.384316Z","caller":"traceutil/trace.go:171","msg":"trace[2140969431] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"304.098094ms","start":"2026-06-10T08:56:27.080185Z","end":"2026-06-10T08:56:27.384283Z","steps":["trace[2140969431] 'read index received' (duration: 303.87356ms)","trace[2140969431] 'applied index is now lower than readState.Index' (duration: 223.514µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:56:27.384357Z","caller":"traceutil/trace.go:171","msg":"trace[1025825165] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"892.434452ms","start":"2026-06-10T08:56:26.491891Z","end":"2026-06-10T08:56:27.384325Z","steps":["trace[1025825165] 'process raft request' (duration: 892.263308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:27.384529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.338618ms","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-06-10T08:56:27.384563Z","caller":"traceutil/trace.go:171","msg":"trace[844618397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:807; }","duration":"304.41975ms","start":"2026-06-10T08:56:27.080133Z","end":"2026-06-10T08:56:27.384553Z","steps":["trace[844618397] 'agreement among raft nodes before linearized reading' (duration: 304.279457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:56:27.384597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:27.080112Z","time spent":"304.476511ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T08:56:27.384601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:56:26.491864Z","time spent":"892.573664ms","remote":"127.0.0.1:43520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:56:55.919076Z","caller":"traceutil/trace.go:171","msg":"trace[1548319462] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"105.512869ms","start":"2026-06-10T08:56:55.813544Z","end":"2026-06-10T08:56:55.919057Z","steps":["trace[1548319462] 'process raft request' (duration: 105.411037ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:56:55.923273Z","caller":"traceutil/trace.go:171","msg":"trace[115019876] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"108.052229ms","start":"2026-06-10T08:56:55.815197Z","end":"2026-06-10T08:56:55.92325Z","steps":["trace[115019876] 'process raft request' (duration: 107.991727ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:56:55.923292Z","caller":"traceutil/trace.go:171","msg":"trace[2094101928] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"109.462266ms","start":"2026-06-10T08:56:55.813812Z","end":"2026-06-10T08:56:55.923274Z","steps":["trace[2094101928] 'process raft request' (duration: 109.268722ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:57:01.469464Z","caller":"traceutil/trace.go:171","msg":"trace[597135835] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"185.939662ms","start":"2026-06-10T08:57:01.283498Z","end":"2026-06-10T08:57:01.469437Z","steps":["trace[597135835] 'process raft request' (duration: 185.714948ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:57:01.901778Z","caller":"traceutil/trace.go:171","msg":"trace[1644402399] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"331.344106ms","start":"2026-06-10T08:57:01.570407Z","end":"2026-06-10T08:57:01.901751Z","steps":["trace[1644402399] 'process raft request' (duration: 331.244264ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:01.901929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:01.570395Z","time spent":"331.462648ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:57:02.443547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16908939282906691234,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:57:02.943963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16908939282906691234,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:57:03.052757Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.151079758s","expected-duration":"1s"} {"level":"info","ts":"2026-06-10T08:57:03.104694Z","caller":"traceutil/trace.go:171","msg":"trace[638454238] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"1.161880238s","start":"2026-06-10T08:57:01.94279Z","end":"2026-06-10T08:57:03.10467Z","steps":["trace[638454238] 'read index received' (duration: 1.110179434s)","trace[638454238] 'applied index is now lower than readState.Index' (duration: 51.699524ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:57:03.104783Z","caller":"traceutil/trace.go:171","msg":"trace[1761158847] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.532074938s","start":"2026-06-10T08:57:01.572686Z","end":"2026-06-10T08:57:03.104761Z","steps":["trace[1761158847] 'process raft request' (duration: 1.480368325s)","trace[1761158847] 'compare' (duration: 51.360307ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:57:03.104902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:01.572664Z","time spent":"1.53216902s","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:57:03.104922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162114972s","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-06-10T08:57:03.104956Z","caller":"traceutil/trace.go:171","msg":"trace[1879505247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1021; }","duration":"1.162207964s","start":"2026-06-10T08:57:01.942738Z","end":"2026-06-10T08:57:03.104946Z","steps":["trace[1879505247] 'agreement among raft nodes before linearized reading' (duration: 1.162055191s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:03.104988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:01.942716Z","time spent":"1.162264335s","remote":"127.0.0.1:43552","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-06-10T08:57:03.105144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161392007s","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-06-10T08:57:03.105169Z","caller":"traceutil/trace.go:171","msg":"trace[404692964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1021; }","duration":"1.161438478s","start":"2026-06-10T08:57:01.943723Z","end":"2026-06-10T08:57:03.105161Z","steps":["trace[404692964] 'agreement among raft nodes before linearized reading' (duration: 1.161359306s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:03.105194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:01.943712Z","time spent":"1.161476279s","remote":"127.0.0.1:43552","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-06-10T08:57:03.105298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161394097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-10T08:57:03.105192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.101627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:57:03.105327Z","caller":"traceutil/trace.go:171","msg":"trace[1937349397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"1.161433449s","start":"2026-06-10T08:57:01.943883Z","end":"2026-06-10T08:57:03.105317Z","steps":["trace[1937349397] 'agreement among raft nodes before linearized reading' (duration: 1.161347766s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:03.105351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:01.943877Z","time spent":"1.1614676s","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T08:57:03.105345Z","caller":"traceutil/trace.go:171","msg":"trace[427004869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"631.284981ms","start":"2026-06-10T08:57:02.474048Z","end":"2026-06-10T08:57:03.105333Z","steps":["trace[427004869] 'agreement among raft nodes before linearized reading' (duration: 631.120177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:03.105378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:02.474029Z","time spent":"631.341403ms","remote":"127.0.0.1:43236","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-06-10T08:57:03.105531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041730854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:57:03.10563Z","caller":"traceutil/trace.go:171","msg":"trace[194030232] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1021; }","duration":"1.041910047s","start":"2026-06-10T08:57:02.063698Z","end":"2026-06-10T08:57:03.105608Z","steps":["trace[194030232] 'agreement among raft nodes before linearized reading' (duration: 1.041692313s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:03.105727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:02.063679Z","time spent":"1.041998529s","remote":"127.0.0.1:43520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-06-10T08:57:03.716744Z","caller":"traceutil/trace.go:171","msg":"trace[1823658319] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"607.365648ms","start":"2026-06-10T08:57:03.109352Z","end":"2026-06-10T08:57:03.716717Z","steps":["trace[1823658319] 'process raft request' (duration: 594.997198ms)","trace[1823658319] 'compare' (duration: 12.223307ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:57:03.716892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:03.109299Z","time spent":"607.517021ms","remote":"127.0.0.1:43552","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-06-10T08:57:03.716927Z","caller":"traceutil/trace.go:171","msg":"trace[13831354] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1062; }","duration":"296.221103ms","start":"2026-06-10T08:57:03.420689Z","end":"2026-06-10T08:57:03.71691Z","steps":["trace[13831354] 'read index received' (duration: 283.67573ms)","trace[13831354] 'applied index is now lower than readState.Index' (duration: 12.544513ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:57:03.717061Z","caller":"traceutil/trace.go:171","msg":"trace[607325819] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"607.597692ms","start":"2026-06-10T08:57:03.109456Z","end":"2026-06-10T08:57:03.717053Z","steps":["trace[607325819] 'process raft request' (duration: 607.223785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:03.717144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:03.10945Z","time spent":"607.636123ms","remote":"127.0.0.1:43552","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-06-10T08:57:03.717278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.422408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:57:03.717306Z","caller":"traceutil/trace.go:171","msg":"trace[408839574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"245.483519ms","start":"2026-06-10T08:57:03.471816Z","end":"2026-06-10T08:57:03.7173Z","steps":["trace[408839574] 'agreement among raft nodes before linearized reading' (duration: 245.434288ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:57:03.717425Z","caller":"traceutil/trace.go:171","msg":"trace[1250856643] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"242.369147ms","start":"2026-06-10T08:57:03.475029Z","end":"2026-06-10T08:57:03.717398Z","steps":["trace[1250856643] 'process raft request' (duration: 241.819566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:03.717439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.163377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-10T08:57:03.717552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.863646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:57:03.717576Z","caller":"traceutil/trace.go:171","msg":"trace[889279161] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1025; }","duration":"296.914907ms","start":"2026-06-10T08:57:03.420653Z","end":"2026-06-10T08:57:03.717568Z","steps":["trace[889279161] 'agreement among raft nodes before linearized reading' (duration: 296.57139ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:57:03.717575Z","caller":"traceutil/trace.go:171","msg":"trace[1686468343] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1025; }","duration":"184.29618ms","start":"2026-06-10T08:57:03.533267Z","end":"2026-06-10T08:57:03.717564Z","steps":["trace[1686468343] 'agreement among raft nodes before linearized reading' (duration: 184.148877ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:27.407715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.210486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:57:27.407809Z","caller":"traceutil/trace.go:171","msg":"trace[1900740830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"935.355288ms","start":"2026-06-10T08:57:26.472433Z","end":"2026-06-10T08:57:27.407788Z","steps":["trace[1900740830] 'range keys from in-memory index tree' (duration: 935.070854ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:27.407851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:26.472411Z","time spent":"935.43128ms","remote":"127.0.0.1:43226","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-06-10T08:57:27.40788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16757758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:57:27.407947Z","caller":"traceutil/trace.go:171","msg":"trace[2134558711] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1099; }","duration":"1.167697361s","start":"2026-06-10T08:57:26.240232Z","end":"2026-06-10T08:57:27.40793Z","steps":["trace[2134558711] 'count revisions from in-memory index tree' (duration: 1.167476907s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:27.407975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:26.240209Z","time spent":"1.167760103s","remote":"127.0.0.1:51972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:57:27.407973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:26.727983Z","time spent":"679.983619ms","remote":"127.0.0.1:42196","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-10T08:57:27.407656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.184285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:57:27.40806Z","caller":"traceutil/trace.go:171","msg":"trace[552548059] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1099; }","duration":"883.625593ms","start":"2026-06-10T08:57:26.524415Z","end":"2026-06-10T08:57:27.408041Z","steps":["trace[552548059] 'count revisions from in-memory index tree' (duration: 883.056953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:27.408243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:26.524393Z","time spent":"883.834738ms","remote":"127.0.0.1:43592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:57:27.408473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.341382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:57:27.408541Z","caller":"traceutil/trace.go:171","msg":"trace[1730116902] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"756.850551ms","start":"2026-06-10T08:57:26.651677Z","end":"2026-06-10T08:57:27.408528Z","steps":["trace[1730116902] 'read index received' (duration: 314.317555ms)","trace[1730116902] 'applied index is now lower than readState.Index' (duration: 442.531936ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:57:27.408842Z","caller":"traceutil/trace.go:171","msg":"trace[1371201078] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"879.083385ms","start":"2026-06-10T08:57:26.529745Z","end":"2026-06-10T08:57:27.408829Z","steps":["trace[1371201078] 'process raft request' (duration: 436.319595ms)","trace[1371201078] 'compare' (duration: 441.584788ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:57:27.408905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.972379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:57:27.408903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:26.529726Z","time spent":"879.143647ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:57:27.408932Z","caller":"traceutil/trace.go:171","msg":"trace[198687632] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1100; }","duration":"436.00355ms","start":"2026-06-10T08:57:26.972921Z","end":"2026-06-10T08:57:27.408925Z","steps":["trace[198687632] 'agreement among raft nodes before linearized reading' (duration: 435.942439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:27.408952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:26.972901Z","time spent":"436.04577ms","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:57:27.409017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.346011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-10T08:57:27.409037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.963798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T08:57:27.409052Z","caller":"traceutil/trace.go:171","msg":"trace[451870291] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1100; }","duration":"757.421633ms","start":"2026-06-10T08:57:26.651614Z","end":"2026-06-10T08:57:27.409036Z","steps":["trace[451870291] 'agreement among raft nodes before linearized reading' (duration: 757.370142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:27.409072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.110853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T08:57:27.409081Z","caller":"traceutil/trace.go:171","msg":"trace[907642564] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1100; }","duration":"368.033489ms","start":"2026-06-10T08:57:27.041036Z","end":"2026-06-10T08:57:27.409069Z","steps":["trace[907642564] 'agreement among raft nodes before linearized reading' (duration: 367.956438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:27.409082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:26.651589Z","time spent":"757.485894ms","remote":"127.0.0.1:43350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-06-10T08:57:27.409094Z","caller":"traceutil/trace.go:171","msg":"trace[587321755] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1100; }","duration":"398.162235ms","start":"2026-06-10T08:57:27.010926Z","end":"2026-06-10T08:57:27.409088Z","steps":["trace[587321755] 'agreement among raft nodes before linearized reading' (duration: 398.125224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:27.409059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.068024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-10T08:57:27.409116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:27.010916Z","time spent":"398.196105ms","remote":"127.0.0.1:41216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:57:27.409116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:27.041025Z","time spent":"368.08271ms","remote":"127.0.0.1:43676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-06-10T08:57:27.409138Z","caller":"traceutil/trace.go:171","msg":"trace[612266123] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1100; }","duration":"187.220877ms","start":"2026-06-10T08:57:27.221899Z","end":"2026-06-10T08:57:27.40912Z","steps":["trace[612266123] 'agreement among raft nodes before linearized reading' (duration: 187.057214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:27.409257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.179818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-10T08:57:27.409332Z","caller":"traceutil/trace.go:171","msg":"trace[2013494116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1100; }","duration":"164.324492ms","start":"2026-06-10T08:57:27.244988Z","end":"2026-06-10T08:57:27.409312Z","steps":["trace[2013494116] 'agreement among raft nodes before linearized reading' (duration: 164.040377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:27.409364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.40186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-10T08:57:27.409397Z","caller":"traceutil/trace.go:171","msg":"trace[781408061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1100; }","duration":"205.465211ms","start":"2026-06-10T08:57:27.203922Z","end":"2026-06-10T08:57:27.409387Z","steps":["trace[781408061] 'agreement among raft nodes before linearized reading' (duration: 205.334359ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:57:27.592494Z","caller":"traceutil/trace.go:171","msg":"trace[214012100] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1145; }","duration":"117.729316ms","start":"2026-06-10T08:57:27.474718Z","end":"2026-06-10T08:57:27.592447Z","steps":["trace[214012100] 'read index received' (duration: 8.389783ms)","trace[214012100] 'applied index is now lower than readState.Index' (duration: 109.338373ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:57:27.592597Z","caller":"traceutil/trace.go:171","msg":"trace[1609279620] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"178.481887ms","start":"2026-06-10T08:57:27.414076Z","end":"2026-06-10T08:57:27.592558Z","steps":["trace[1609279620] 'process raft request' (duration: 178.231672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:27.592638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.90959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:57:27.592947Z","caller":"traceutil/trace.go:171","msg":"trace[151217502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1102; }","duration":"118.275467ms","start":"2026-06-10T08:57:27.474656Z","end":"2026-06-10T08:57:27.592931Z","steps":["trace[151217502] 'agreement among raft nodes before linearized reading' (duration: 117.90667ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:57:27.923809Z","caller":"traceutil/trace.go:171","msg":"trace[340733835] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"114.716428ms","start":"2026-06-10T08:57:27.809068Z","end":"2026-06-10T08:57:27.923784Z","steps":["trace[340733835] 'process raft request' (duration: 114.29917ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:28.222237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.679087ms","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-06-10T08:57:28.222335Z","caller":"traceutil/trace.go:171","msg":"trace[1399368591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1105; }","duration":"132.093265ms","start":"2026-06-10T08:57:28.090218Z","end":"2026-06-10T08:57:28.222312Z","steps":["trace[1399368591] 'range keys from in-memory index tree' (duration: 131.459523ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:57:28.521266Z","caller":"traceutil/trace.go:171","msg":"trace[1840578494] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"294.572691ms","start":"2026-06-10T08:57:28.226664Z","end":"2026-06-10T08:57:28.521237Z","steps":["trace[1840578494] 'process raft request' (duration: 294.301756ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:57:29.710865Z","caller":"traceutil/trace.go:171","msg":"trace[1401484889] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"113.182498ms","start":"2026-06-10T08:57:29.597659Z","end":"2026-06-10T08:57:29.710841Z","steps":["trace[1401484889] 'read index received' (duration: 112.908212ms)","trace[1401484889] 'applied index is now lower than readState.Index' (duration: 273.216µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:57:29.710962Z","caller":"traceutil/trace.go:171","msg":"trace[89140254] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"219.896411ms","start":"2026-06-10T08:57:29.491037Z","end":"2026-06-10T08:57:29.710934Z","steps":["trace[89140254] 'process raft request' (duration: 219.651437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:29.71119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.411722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-10T08:57:29.711242Z","caller":"traceutil/trace.go:171","msg":"trace[748224552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1107; }","duration":"113.625547ms","start":"2026-06-10T08:57:29.597602Z","end":"2026-06-10T08:57:29.711228Z","steps":["trace[748224552] 'agreement among raft nodes before linearized reading' (duration: 113.353062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:30.765578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.418872ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-10T08:57:30.765955Z","caller":"traceutil/trace.go:171","msg":"trace[2055053533] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"134.264988ms","start":"2026-06-10T08:57:30.631652Z","end":"2026-06-10T08:57:30.765917Z","steps":["trace[2055053533] 'process raft request' (duration: 134.023623ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:57:30.76587Z","caller":"traceutil/trace.go:171","msg":"trace[83507814] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1156; }","duration":"293.642904ms","start":"2026-06-10T08:57:30.472158Z","end":"2026-06-10T08:57:30.765801Z","steps":["trace[83507814] 'read index received' (duration: 39.990167ms)","trace[83507814] 'applied index is now lower than readState.Index' (duration: 253.651177ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:57:30.765991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.816227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:57:30.766056Z","caller":"traceutil/trace.go:171","msg":"trace[993857133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1112; }","duration":"293.927018ms","start":"2026-06-10T08:57:30.472112Z","end":"2026-06-10T08:57:30.766039Z","steps":["trace[993857133] 'agreement among raft nodes before linearized reading' (duration: 293.777955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:30.766248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.131084ms","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-06-10T08:57:30.766309Z","caller":"traceutil/trace.go:171","msg":"trace[1409239329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1112; }","duration":"241.242856ms","start":"2026-06-10T08:57:30.525047Z","end":"2026-06-10T08:57:30.76629Z","steps":["trace[1409239329] 'agreement among raft nodes before linearized reading' (duration: 241.037402ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:57:31.315554Z","caller":"traceutil/trace.go:171","msg":"trace[1664111285] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"544.237572ms","start":"2026-06-10T08:57:30.771289Z","end":"2026-06-10T08:57:31.315526Z","steps":["trace[1664111285] 'process raft request' (duration: 544.089729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:31.315821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:30.771277Z","time spent":"544.454006ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:57:31.654696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.321605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:57:31.65504Z","caller":"traceutil/trace.go:171","msg":"trace[1788106485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1113; }","duration":"107.523469ms","start":"2026-06-10T08:57:31.54725Z","end":"2026-06-10T08:57:31.654773Z","steps":["trace[1788106485] 'range keys from in-memory index tree' (duration: 107.226334ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:57:48.095483Z","caller":"traceutil/trace.go:171","msg":"trace[1910421719] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"650.19308ms","start":"2026-06-10T08:57:47.44526Z","end":"2026-06-10T08:57:48.095453Z","steps":["trace[1910421719] 'process raft request' (duration: 650.003757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:48.095666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:47.445234Z","time spent":"650.340033ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:57:48.186615Z","caller":"traceutil/trace.go:171","msg":"trace[1505283747] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"556.325944ms","start":"2026-06-10T08:57:47.630266Z","end":"2026-06-10T08:57:48.186592Z","steps":["trace[1505283747] 'process raft request' (duration: 556.09638ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:57:48.186613Z","caller":"traceutil/trace.go:171","msg":"trace[468379105] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1266; }","duration":"385.579845ms","start":"2026-06-10T08:57:47.800965Z","end":"2026-06-10T08:57:48.186544Z","steps":["trace[468379105] 'read index received' (duration: 294.35108ms)","trace[468379105] 'applied index is now lower than readState.Index' (duration: 91.227705ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:57:48.186662Z","caller":"traceutil/trace.go:171","msg":"trace[1835036357] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"184.315246ms","start":"2026-06-10T08:57:48.002318Z","end":"2026-06-10T08:57:48.186634Z","steps":["trace[1835036357] 'process raft request' (duration: 184.180983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:48.186758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:47.630231Z","time spent":"556.453407ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:57:48.186876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.887763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-10T08:57:48.186924Z","caller":"traceutil/trace.go:171","msg":"trace[823379552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1218; }","duration":"386.075616ms","start":"2026-06-10T08:57:47.800837Z","end":"2026-06-10T08:57:48.186913Z","steps":["trace[823379552] 'agreement among raft nodes before linearized reading' (duration: 385.899082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:48.186928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.041829ms","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-06-10T08:57:48.186953Z","caller":"traceutil/trace.go:171","msg":"trace[1443301376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1218; }","duration":"371.107191ms","start":"2026-06-10T08:57:47.815839Z","end":"2026-06-10T08:57:48.186947Z","steps":["trace[1443301376] 'agreement among raft nodes before linearized reading' (duration: 370.981268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:57:48.186962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:47.800813Z","time spent":"386.139957ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:57:48.186979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:57:47.815829Z","time spent":"371.140661ms","remote":"127.0.0.1:43552","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-06-10T08:58:00.761222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.170742ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-10T08:58:00.761326Z","caller":"traceutil/trace.go:171","msg":"trace[1788710449] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"173.529292ms","start":"2026-06-10T08:58:00.58778Z","end":"2026-06-10T08:58:00.761309Z","steps":["trace[1788710449] 'read index received' (duration: 45.872µs)","trace[1788710449] 'applied index is now lower than readState.Index' (duration: 173.48208ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:58:00.761419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.625444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:58:00.761452Z","caller":"traceutil/trace.go:171","msg":"trace[395673922] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1261; }","duration":"173.708765ms","start":"2026-06-10T08:58:00.587734Z","end":"2026-06-10T08:58:00.761443Z","steps":["trace[395673922] 'agreement among raft nodes before linearized reading' (duration: 173.637123ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:58:03.989483Z","caller":"traceutil/trace.go:171","msg":"trace[1080128875] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"158.19088ms","start":"2026-06-10T08:58:03.831267Z","end":"2026-06-10T08:58:03.989458Z","steps":["trace[1080128875] 'process raft request' (duration: 158.003256ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:58:04.617372Z","caller":"traceutil/trace.go:171","msg":"trace[1997963870] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"428.573264ms","start":"2026-06-10T08:58:04.188777Z","end":"2026-06-10T08:58:04.61735Z","steps":["trace[1997963870] 'process raft request' (duration: 428.454572ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:04.61751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:04.18874Z","time spent":"428.698197ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:58:05.650687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.16571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:58:05.650791Z","caller":"traceutil/trace.go:171","msg":"trace[2021909575] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"1.38441859s","start":"2026-06-10T08:58:04.266355Z","end":"2026-06-10T08:58:05.650774Z","steps":["trace[2021909575] 'read index received' (duration: 351.308614ms)","trace[2021909575] 'applied index is now lower than readState.Index' (duration: 1.033108436s)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:58:05.651025Z","caller":"traceutil/trace.go:171","msg":"trace[124794309] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"1.413914401s","start":"2026-06-10T08:58:04.237097Z","end":"2026-06-10T08:58:05.651011Z","steps":["trace[124794309] 'process raft request' (duration: 1.256337053s)","trace[124794309] 'compare' (duration: 156.754472ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:58:05.651102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:04.237074Z","time spent":"1.413991892s","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:58:05.651228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370577707s","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-06-10T08:58:05.651314Z","caller":"traceutil/trace.go:171","msg":"trace[509423538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1290; }","duration":"1.370693429s","start":"2026-06-10T08:58:04.280598Z","end":"2026-06-10T08:58:05.651292Z","steps":["trace[509423538] 'agreement among raft nodes before linearized reading' (duration: 1.370437264s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:05.651383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:04.280585Z","time spent":"1.370779541s","remote":"127.0.0.1:43552","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-06-10T08:58:05.65142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318516557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:58:05.651456Z","caller":"traceutil/trace.go:171","msg":"trace[2048166971] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1290; }","duration":"1.318575677s","start":"2026-06-10T08:58:04.33287Z","end":"2026-06-10T08:58:05.651446Z","steps":["trace[2048166971] 'agreement among raft nodes before linearized reading' (duration: 1.318510526s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:05.651496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:04.332859Z","time spent":"1.318627788s","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:58:05.651613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337766382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:58:05.651654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385296656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:58:05.651659Z","caller":"traceutil/trace.go:171","msg":"trace[682353610] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1290; }","duration":"1.337836453s","start":"2026-06-10T08:58:04.313811Z","end":"2026-06-10T08:58:05.651647Z","steps":["trace[682353610] 'agreement among raft nodes before linearized reading' (duration: 1.337765212s)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:58:05.651688Z","caller":"traceutil/trace.go:171","msg":"trace[1013576253] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1290; }","duration":"1.385372598s","start":"2026-06-10T08:58:04.266305Z","end":"2026-06-10T08:58:05.651678Z","steps":["trace[1013576253] 'agreement among raft nodes before linearized reading' (duration: 1.385320947s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:05.651698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:04.313801Z","time spent":"1.337887674s","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:58:05.651725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:04.26628Z","time spent":"1.385436698s","remote":"127.0.0.1:51930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:58:05.651868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.537335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:58:05.6519Z","caller":"traceutil/trace.go:171","msg":"trace[1042796653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"180.599766ms","start":"2026-06-10T08:58:05.471293Z","end":"2026-06-10T08:58:05.651893Z","steps":["trace[1042796653] 'agreement among raft nodes before linearized reading' (duration: 180.554626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:05.65191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.368415485s","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-06-10T08:58:05.65194Z","caller":"traceutil/trace.go:171","msg":"trace[2118008994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1290; }","duration":"1.368455786s","start":"2026-06-10T08:58:04.283475Z","end":"2026-06-10T08:58:05.651931Z","steps":["trace[2118008994] 'agreement among raft nodes before linearized reading' (duration: 1.368359874s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:05.651976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:04.283472Z","time spent":"1.368497377s","remote":"127.0.0.1:43552","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-06-10T08:58:05.652094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.982351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.111\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-10T08:58:05.652128Z","caller":"traceutil/trace.go:171","msg":"trace[497462969] range","detail":"{range_begin:/registry/masterleases/199.19.213.111; range_end:; response_count:1; response_revision:1290; }","duration":"359.055142ms","start":"2026-06-10T08:58:05.293064Z","end":"2026-06-10T08:58:05.65212Z","steps":["trace[497462969] 'agreement among raft nodes before linearized reading' (duration: 358.935958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:05.652154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:05.293044Z","time spent":"359.104403ms","remote":"127.0.0.1:43278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.111\" "} {"level":"warn","ts":"2026-06-10T08:58:05.652158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.368686031s","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-06-10T08:58:05.652193Z","caller":"traceutil/trace.go:171","msg":"trace[1027884630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1290; }","duration":"1.368734562s","start":"2026-06-10T08:58:04.283449Z","end":"2026-06-10T08:58:05.652184Z","steps":["trace[1027884630] 'agreement among raft nodes before linearized reading' (duration: 1.36863224s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:05.652228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:04.283443Z","time spent":"1.368778992s","remote":"127.0.0.1:43552","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-06-10T08:58:05.652418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371753509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-10T08:58:05.652463Z","caller":"traceutil/trace.go:171","msg":"trace[765362197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1290; }","duration":"1.371810951s","start":"2026-06-10T08:58:04.280642Z","end":"2026-06-10T08:58:05.652453Z","steps":["trace[765362197] 'agreement among raft nodes before linearized reading' (duration: 1.371707629s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:05.652497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:04.280636Z","time spent":"1.371854331s","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:58:06.973302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16908939282906691881,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:58:07.473697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16908939282906691881,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:58:07.687061Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.245918185s","expected-duration":"1s"} {"level":"info","ts":"2026-06-10T08:58:07.68741Z","caller":"traceutil/trace.go:171","msg":"trace[270973500] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"1.21517174s","start":"2026-06-10T08:58:06.472213Z","end":"2026-06-10T08:58:07.687385Z","steps":["trace[270973500] 'read index received' (duration: 1.215036337s)","trace[270973500] 'applied index is now lower than readState.Index' (duration: 134.163µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:58:07.687526Z","caller":"traceutil/trace.go:171","msg":"trace[287614134] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"1.246535646s","start":"2026-06-10T08:58:06.440979Z","end":"2026-06-10T08:58:07.687515Z","steps":["trace[287614134] 'process raft request' (duration: 1.246267551s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:07.687623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:06.440946Z","time spent":"1.246614758s","remote":"127.0.0.1:43324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:58:07.687766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215536976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:58:07.687838Z","caller":"traceutil/trace.go:171","msg":"trace[1516125461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1296; }","duration":"1.215662359s","start":"2026-06-10T08:58:06.472157Z","end":"2026-06-10T08:58:07.687819Z","steps":["trace[1516125461] 'agreement among raft nodes before linearized reading' (duration: 1.215463445s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:07.687874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:06.472134Z","time spent":"1.215732851s","remote":"127.0.0.1:43226","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-06-10T08:58:08.002646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.346345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:58:08.002754Z","caller":"traceutil/trace.go:171","msg":"trace[1938796498] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"315.255928ms","start":"2026-06-10T08:58:07.687481Z","end":"2026-06-10T08:58:08.002737Z","steps":["trace[1938796498] 'read index received' (duration: 159.629338ms)","trace[1938796498] 'applied index is now lower than readState.Index' (duration: 155.62545ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:58:08.002831Z","caller":"traceutil/trace.go:171","msg":"trace[1612492626] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"345.497602ms","start":"2026-06-10T08:58:07.657298Z","end":"2026-06-10T08:58:08.002796Z","steps":["trace[1612492626] 'process raft request' (duration: 189.878472ms)","trace[1612492626] 'compare' (duration: 155.239703ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:58:08.002859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022612415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:58:08.002892Z","caller":"traceutil/trace.go:171","msg":"trace[625396662] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1297; }","duration":"1.022685707s","start":"2026-06-10T08:58:06.980196Z","end":"2026-06-10T08:58:08.002882Z","steps":["trace[625396662] 'agreement among raft nodes before linearized reading' (duration: 1.022625216s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:08.002927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:06.980175Z","time spent":"1.022742239s","remote":"127.0.0.1:46718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:58:08.002954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:07.657273Z","time spent":"345.612445ms","remote":"127.0.0.1:43552","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-06-10T08:58:08.003083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.30485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:58:08.003166Z","caller":"traceutil/trace.go:171","msg":"trace[1834507882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"314.428763ms","start":"2026-06-10T08:58:07.68872Z","end":"2026-06-10T08:58:08.003149Z","steps":["trace[1834507882] 'agreement among raft nodes before linearized reading' (duration: 314.28234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:08.003176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.118651ms","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-06-10T08:58:08.00319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.634371ms","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-06-10T08:58:08.003217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.204062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-10T08:58:08.003218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:07.688704Z","time spent":"314.499904ms","remote":"127.0.0.1:43236","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-06-10T08:58:08.003224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.381746859s","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-06-10T08:58:08.003225Z","caller":"traceutil/trace.go:171","msg":"trace[1999941540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1297; }","duration":"290.182682ms","start":"2026-06-10T08:58:07.713029Z","end":"2026-06-10T08:58:08.003212Z","steps":["trace[1999941540] 'agreement among raft nodes before linearized reading' (duration: 290.013389ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:58:08.003253Z","caller":"traceutil/trace.go:171","msg":"trace[165803428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1297; }","duration":"1.38181779s","start":"2026-06-10T08:58:06.621429Z","end":"2026-06-10T08:58:08.003247Z","steps":["trace[165803428] 'agreement among raft nodes before linearized reading' (duration: 1.381718889s)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:58:08.003249Z","caller":"traceutil/trace.go:171","msg":"trace[1336722263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1297; }","duration":"290.256333ms","start":"2026-06-10T08:58:07.712984Z","end":"2026-06-10T08:58:08.00324Z","steps":["trace[1336722263] 'agreement among raft nodes before linearized reading' (duration: 290.162152ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:58:08.00325Z","caller":"traceutil/trace.go:171","msg":"trace[298201353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1297; }","duration":"289.711412ms","start":"2026-06-10T08:58:07.713522Z","end":"2026-06-10T08:58:08.003233Z","steps":["trace[298201353] 'agreement among raft nodes before linearized reading' (duration: 289.539779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:08.003275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:06.621408Z","time spent":"1.381863161s","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T08:58:08.003336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.316737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:58:08.00335Z","caller":"traceutil/trace.go:171","msg":"trace[1629498110] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1297; }","duration":"194.334137ms","start":"2026-06-10T08:58:07.809011Z","end":"2026-06-10T08:58:08.003345Z","steps":["trace[1629498110] 'agreement among raft nodes before linearized reading' (duration: 194.307087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:08.003632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.940406ms","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-06-10T08:58:08.003687Z","caller":"traceutil/trace.go:171","msg":"trace[291016734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1297; }","duration":"290.007467ms","start":"2026-06-10T08:58:07.713663Z","end":"2026-06-10T08:58:08.00367Z","steps":["trace[291016734] 'agreement among raft nodes before linearized reading' (duration: 289.362165ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:58:08.164351Z","caller":"traceutil/trace.go:171","msg":"trace[444590936] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1354; }","duration":"148.266721ms","start":"2026-06-10T08:58:08.016059Z","end":"2026-06-10T08:58:08.164326Z","steps":["trace[444590936] 'read index received' (duration: 37.469023ms)","trace[444590936] 'applied index is now lower than readState.Index' (duration: 110.796678ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:58:08.164406Z","caller":"traceutil/trace.go:171","msg":"trace[1875822088] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"154.189133ms","start":"2026-06-10T08:58:08.010181Z","end":"2026-06-10T08:58:08.16437Z","steps":["trace[1875822088] 'process raft request' (duration: 154.04014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:08.164483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.404204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:58:08.164519Z","caller":"traceutil/trace.go:171","msg":"trace[193635911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"148.457165ms","start":"2026-06-10T08:58:08.01605Z","end":"2026-06-10T08:58:08.164507Z","steps":["trace[193635911] 'agreement among raft nodes before linearized reading' (duration: 148.378653ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:58:08.164533Z","caller":"traceutil/trace.go:171","msg":"trace[1714836641] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"155.327956ms","start":"2026-06-10T08:58:08.009176Z","end":"2026-06-10T08:58:08.164504Z","steps":["trace[1714836641] 'process raft request' (duration: 103.51596ms)","trace[1714836641] 'compare' (duration: 51.396338ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:58:08.164699Z","caller":"traceutil/trace.go:171","msg":"trace[673160282] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"153.954159ms","start":"2026-06-10T08:58:08.010732Z","end":"2026-06-10T08:58:08.164686Z","steps":["trace[673160282] 'process raft request' (duration: 153.544671ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:58:09.056568Z","caller":"traceutil/trace.go:171","msg":"trace[1726057465] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"468.251438ms","start":"2026-06-10T08:58:08.588299Z","end":"2026-06-10T08:58:09.05655Z","steps":["trace[1726057465] 'read index received' (duration: 468.076615ms)","trace[1726057465] 'applied index is now lower than readState.Index' (duration: 174.003µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:58:09.056696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.388011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:58:09.056723Z","caller":"traceutil/trace.go:171","msg":"trace[483024967] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1303; }","duration":"468.476302ms","start":"2026-06-10T08:58:08.588235Z","end":"2026-06-10T08:58:09.056712Z","steps":["trace[483024967] 'agreement among raft nodes before linearized reading' (duration: 468.390671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:09.056749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:08.588117Z","time spent":"468.624967ms","remote":"127.0.0.1:43420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-06-10T08:58:09.056781Z","caller":"traceutil/trace.go:171","msg":"trace[1923187637] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"570.557485ms","start":"2026-06-10T08:58:08.486191Z","end":"2026-06-10T08:58:09.056748Z","steps":["trace[1923187637] 'process raft request' (duration: 570.234509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:09.056936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:08.486159Z","time spent":"570.688547ms","remote":"127.0.0.1:43552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:58:09.707213Z","caller":"traceutil/trace.go:171","msg":"trace[6078922] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"236.813506ms","start":"2026-06-10T08:58:09.470385Z","end":"2026-06-10T08:58:09.707198Z","steps":["trace[6078922] 'read index received' (duration: 236.694333ms)","trace[6078922] 'applied index is now lower than readState.Index' (duration: 118.633µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:58:09.70733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.926858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:58:09.707367Z","caller":"traceutil/trace.go:171","msg":"trace[606096236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"237.014938ms","start":"2026-06-10T08:58:09.470343Z","end":"2026-06-10T08:58:09.707358Z","steps":["trace[606096236] 'agreement among raft nodes before linearized reading' (duration: 236.926067ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:58:09.707572Z","caller":"traceutil/trace.go:171","msg":"trace[433489603] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"463.002139ms","start":"2026-06-10T08:58:09.244561Z","end":"2026-06-10T08:58:09.707563Z","steps":["trace[433489603] 'process raft request' (duration: 462.530201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:09.707629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:09.244543Z","time spent":"463.04675ms","remote":"127.0.0.1:43324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:58:18.305462Z","caller":"traceutil/trace.go:171","msg":"trace[2114419426] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"423.525878ms","start":"2026-06-10T08:58:17.881909Z","end":"2026-06-10T08:58:18.305435Z","steps":["trace[2114419426] 'process raft request' (duration: 423.331885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:18.305678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:58:17.881888Z","time spent":"423.65028ms","remote":"127.0.0.1:43552","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-06-10T08:58:18.30642Z","caller":"traceutil/trace.go:171","msg":"trace[184344611] linearizableReadLoop","detail":"{readStateIndex:1430; appliedIndex:1428; }","duration":"114.813974ms","start":"2026-06-10T08:58:18.19158Z","end":"2026-06-10T08:58:18.306394Z","steps":["trace[184344611] 'read index received' (duration: 113.663783ms)","trace[184344611] 'applied index is now lower than readState.Index' (duration: 1.148741ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:58:18.306547Z","caller":"traceutil/trace.go:171","msg":"trace[106626477] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"271.476825ms","start":"2026-06-10T08:58:18.035035Z","end":"2026-06-10T08:58:18.306512Z","steps":["trace[106626477] 'process raft request' (duration: 271.219319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:18.306649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.05414ms","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-06-10T08:58:18.306687Z","caller":"traceutil/trace.go:171","msg":"trace[1608259796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1373; }","duration":"115.149061ms","start":"2026-06-10T08:58:18.191525Z","end":"2026-06-10T08:58:18.306674Z","steps":["trace[1608259796] 'agreement among raft nodes before linearized reading' (duration: 114.992728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:18.306886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.558029ms","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-06-10T08:58:18.306921Z","caller":"traceutil/trace.go:171","msg":"trace[1783592546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1373; }","duration":"105.63071ms","start":"2026-06-10T08:58:18.20128Z","end":"2026-06-10T08:58:18.306911Z","steps":["trace[1783592546] 'agreement among raft nodes before linearized reading' (duration: 105.552049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:18.307115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.229547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-10T08:58:18.307146Z","caller":"traceutil/trace.go:171","msg":"trace[2098572113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1373; }","duration":"100.277249ms","start":"2026-06-10T08:58:18.206861Z","end":"2026-06-10T08:58:18.307139Z","steps":["trace[2098572113] 'agreement among raft nodes before linearized reading' (duration: 100.208447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:58:18.307252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.89693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-10T08:58:18.307275Z","caller":"traceutil/trace.go:171","msg":"trace[766488647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1373; }","duration":"100.940181ms","start":"2026-06-10T08:58:18.206328Z","end":"2026-06-10T08:58:18.307268Z","steps":["trace[766488647] 'agreement among raft nodes before linearized reading' (duration: 100.87046ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:01:58.990577Z","caller":"traceutil/trace.go:171","msg":"trace[956408919] linearizableReadLoop","detail":"{readStateIndex:2510; appliedIndex:2509; }","duration":"223.165303ms","start":"2026-06-10T09:01:58.767386Z","end":"2026-06-10T09:01:58.990552Z","steps":["trace[956408919] 'read index received' (duration: 222.931589ms)","trace[956408919] 'applied index is now lower than readState.Index' (duration: 232.734µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T09:01:58.990645Z","caller":"traceutil/trace.go:171","msg":"trace[1596188000] transaction","detail":"{read_only:false; response_revision:2408; number_of_response:1; }","duration":"225.963386ms","start":"2026-06-10T09:01:58.764649Z","end":"2026-06-10T09:01:58.990612Z","steps":["trace[1596188000] 'process raft request' (duration: 225.720832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:01:58.990768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.347036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T09:01:58.990919Z","caller":"traceutil/trace.go:171","msg":"trace[1305515272] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2408; }","duration":"223.543479ms","start":"2026-06-10T09:01:58.767357Z","end":"2026-06-10T09:01:58.9909Z","steps":["trace[1305515272] 'agreement among raft nodes before linearized reading' (duration: 223.319475ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:02:06.938562Z","caller":"traceutil/trace.go:171","msg":"trace[1624968364] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"139.482233ms","start":"2026-06-10T09:02:06.79904Z","end":"2026-06-10T09:02:06.938522Z","steps":["trace[1624968364] 'process raft request' (duration: 139.265248ms)"],"step_count":1}