{"level":"warn","ts":"2026-04-14T07:27:01.503187Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T07:27:01.503337Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-14T07:27:01.503443Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T07:27:01.503461Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-14T07:27:01.503486Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-14T07:27:01.504448Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-04-14T07:27:01.50459Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-14T07:27:01.509615Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.707798ms"} {"level":"info","ts":"2026-04-14T07:27:01.519992Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-04-14T07:27:01.520176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T07:27:01.520205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-04-14T07:27:01.520255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T07:27:01.520265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-04-14T07:27:01.52031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-04-14T07:27:01.527188Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T07:27:01.528889Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T07:27:01.531507Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-14T07:27:01.535228Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T07:27:01.535386Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T07:27:01.535609Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-14T07:27:01.535695Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-14T07:27:01.535705Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-14T07:27:01.538362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-04-14T07:27:01.539138Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-14T07:27:01.541743Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-14T07:27:01.54242Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-14T07:27:01.542489Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-14T07:27:01.543306Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T07:27:01.543387Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T07:27:01.921469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T07:27:01.921538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T07:27:01.92156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-04-14T07:27:01.921593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-04-14T07:27:01.921605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-14T07:27:01.92162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-04-14T07:27:01.921634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-14T07:27:01.924324Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T07:27:01.924371Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T07:27:01.924771Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T07:27:01.925523Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T07:27:01.925714Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T07:27:01.925762Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T07:27:01.925804Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T07:27:01.928592Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T07:27:01.928639Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T07:27:01.928735Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-04-14T07:27:01.930196Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-14T07:27:06.797747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.495614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T07:27:06.797927Z","caller":"traceutil/trace.go:171","msg":"trace[781056438] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:255; }","duration":"649.788176ms","start":"2026-04-14T07:27:06.148119Z","end":"2026-04-14T07:27:06.797907Z","steps":["trace[781056438] 'read index received' (duration: 40.394258ms)","trace[781056438] 'applied index is now lower than readState.Index' (duration: 609.392358ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:27:06.802052Z","caller":"traceutil/trace.go:171","msg":"trace[618330718] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"834.298964ms","start":"2026-04-14T07:27:05.96374Z","end":"2026-04-14T07:27:06.798039Z","steps":["trace[618330718] 'process raft request' (duration: 224.712401ms)","trace[618330718] 'compare' (duration: 608.282629ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:27:06.80238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:05.96373Z","time spent":"838.56661ms","remote":"127.0.0.1:54388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":197,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T07:27:06.80249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.322859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"warn","ts":"2026-04-14T07:27:06.802518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.253502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:27:06.802767Z","caller":"traceutil/trace.go:171","msg":"trace[579919970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:250; }","duration":"307.515438ms","start":"2026-04-14T07:27:06.495184Z","end":"2026-04-14T07:27:06.802699Z","steps":["trace[579919970] 'agreement among raft nodes before linearized reading' (duration: 306.894813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:06.802839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:06.495109Z","time spent":"307.712563ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T07:27:06.802589Z","caller":"traceutil/trace.go:171","msg":"trace[1216578804] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:250; }","duration":"654.506004ms","start":"2026-04-14T07:27:06.148049Z","end":"2026-04-14T07:27:06.802555Z","steps":["trace[1216578804] 'agreement among raft nodes before linearized reading' (duration: 654.132124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:06.803505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:06.148017Z","time spent":"655.418967ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-14T07:27:07.250848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.588929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T07:27:07.251067Z","caller":"traceutil/trace.go:171","msg":"trace[1588121482] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"280.919065ms","start":"2026-04-14T07:27:06.970132Z","end":"2026-04-14T07:27:07.251051Z","steps":["trace[1588121482] 'process raft request' (duration: 280.877764ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:07.251103Z","caller":"traceutil/trace.go:171","msg":"trace[261710533] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:256; }","duration":"440.443478ms","start":"2026-04-14T07:27:06.810575Z","end":"2026-04-14T07:27:07.251019Z","steps":["trace[261710533] 'read index received' (duration: 271.412358ms)","trace[261710533] 'applied index is now lower than readState.Index' (duration: 169.02904ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:27:07.251261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.666104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-14T07:27:07.251295Z","caller":"traceutil/trace.go:171","msg":"trace[1536537043] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:253; }","duration":"440.711465ms","start":"2026-04-14T07:27:06.810571Z","end":"2026-04-14T07:27:07.251283Z","steps":["trace[1536537043] 'agreement among raft nodes before linearized reading' (duration: 440.589392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:07.251324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:06.81056Z","time spent":"440.757705ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-14T07:27:07.251534Z","caller":"traceutil/trace.go:171","msg":"trace[1548622864] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"440.423568ms","start":"2026-04-14T07:27:06.811085Z","end":"2026-04-14T07:27:07.251508Z","steps":["trace[1548622864] 'process raft request' (duration: 439.861863ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:07.251544Z","caller":"traceutil/trace.go:171","msg":"trace[1714412691] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"442.548431ms","start":"2026-04-14T07:27:06.808967Z","end":"2026-04-14T07:27:07.251516Z","steps":["trace[1714412691] 'process raft request' (duration: 273.225473ms)","trace[1714412691] 'compare' (duration: 168.450566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:27:07.251622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:06.808948Z","time spent":"442.639553ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T07:27:07.252793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:06.811068Z","time spent":"440.516811ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3694,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:27:10.48342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.464168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-14T07:27:10.483512Z","caller":"traceutil/trace.go:171","msg":"trace[1560976213] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:337; }","duration":"124.653862ms","start":"2026-04-14T07:27:10.358839Z","end":"2026-04-14T07:27:10.483493Z","steps":["trace[1560976213] 'range keys from in-memory index tree' (duration: 124.363044ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:33.494853Z","caller":"traceutil/trace.go:171","msg":"trace[515106811] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"105.922125ms","start":"2026-04-14T07:27:33.388898Z","end":"2026-04-14T07:27:33.49482Z","steps":["trace[515106811] 'process raft request' (duration: 103.125645ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:35.990121Z","caller":"traceutil/trace.go:171","msg":"trace[107807194] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"142.023806ms","start":"2026-04-14T07:27:35.848052Z","end":"2026-04-14T07:27:35.990076Z","steps":["trace[107807194] 'process raft request' (duration: 141.623986ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:35.990251Z","caller":"traceutil/trace.go:171","msg":"trace[1273399037] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"141.913454ms","start":"2026-04-14T07:27:35.848289Z","end":"2026-04-14T07:27:35.990202Z","steps":["trace[1273399037] 'process raft request' (duration: 141.660767ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:35.99077Z","caller":"traceutil/trace.go:171","msg":"trace[243463495] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"131.910244ms","start":"2026-04-14T07:27:35.858842Z","end":"2026-04-14T07:27:35.990752Z","steps":["trace[243463495] 'process raft request' (duration: 131.74377ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:36.03158Z","caller":"traceutil/trace.go:171","msg":"trace[2132343094] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"122.709925ms","start":"2026-04-14T07:27:35.908817Z","end":"2026-04-14T07:27:36.031527Z","steps":["trace[2132343094] 'process raft request' (duration: 122.355646ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:36.541848Z","caller":"traceutil/trace.go:171","msg":"trace[196738780] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"427.767812ms","start":"2026-04-14T07:27:36.114053Z","end":"2026-04-14T07:27:36.54182Z","steps":["trace[196738780] 'process raft request' (duration: 410.44038ms)","trace[196738780] 'compare' (duration: 17.025075ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:27:36.542059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:36.114021Z","time spent":"427.966357ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T07:27:36.542082Z","caller":"traceutil/trace.go:171","msg":"trace[1735106877] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"427.584736ms","start":"2026-04-14T07:27:36.114447Z","end":"2026-04-14T07:27:36.542032Z","steps":["trace[1735106877] 'process raft request' (duration: 427.283249ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:36.542147Z","caller":"traceutil/trace.go:171","msg":"trace[1699331289] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"415.127886ms","start":"2026-04-14T07:27:36.12699Z","end":"2026-04-14T07:27:36.542118Z","steps":["trace[1699331289] 'process raft request' (duration: 414.88273ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:36.542237Z","caller":"traceutil/trace.go:171","msg":"trace[477965766] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"289.170691ms","start":"2026-04-14T07:27:36.253044Z","end":"2026-04-14T07:27:36.542214Z","steps":["trace[477965766] 'process raft request' (duration: 288.951405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:36.542357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:36.126961Z","time spent":"415.31342ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:27:36.54236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:36.114439Z","time spent":"427.793391ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4318,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:27:36.550487Z","caller":"traceutil/trace.go:171","msg":"trace[1635242845] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:622; }","duration":"207.401779ms","start":"2026-04-14T07:27:36.343044Z","end":"2026-04-14T07:27:36.550446Z","steps":["trace[1635242845] 'read index received' (duration: 181.537014ms)","trace[1635242845] 'applied index is now lower than readState.Index' (duration: 25.863865ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:27:36.550773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.707006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-f4fwc\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-04-14T07:27:36.550805Z","caller":"traceutil/trace.go:171","msg":"trace[529940213] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-f4fwc; range_end:; response_count:1; response_revision:603; }","duration":"207.805489ms","start":"2026-04-14T07:27:36.342992Z","end":"2026-04-14T07:27:36.550797Z","steps":["trace[529940213] 'agreement among raft nodes before linearized reading' (duration: 207.587904ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:36.550768Z","caller":"traceutil/trace.go:171","msg":"trace[1551947143] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"118.016957ms","start":"2026-04-14T07:27:36.432709Z","end":"2026-04-14T07:27:36.550726Z","steps":["trace[1551947143] 'process raft request' (duration: 116.005958ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:36.551047Z","caller":"traceutil/trace.go:171","msg":"trace[2104809727] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"102.390927ms","start":"2026-04-14T07:27:36.448644Z","end":"2026-04-14T07:27:36.551035Z","steps":["trace[2104809727] 'process raft request' (duration: 101.739131ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:39.926901Z","caller":"traceutil/trace.go:171","msg":"trace[1210304042] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"149.373111ms","start":"2026-04-14T07:27:39.777474Z","end":"2026-04-14T07:27:39.926847Z","steps":["trace[1210304042] 'process raft request' (duration: 148.175191ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:41.702324Z","caller":"traceutil/trace.go:171","msg":"trace[1435044346] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"252.69012ms","start":"2026-04-14T07:27:41.449604Z","end":"2026-04-14T07:27:41.702294Z","steps":["trace[1435044346] 'read index received' (duration: 251.626393ms)","trace[1435044346] 'applied index is now lower than readState.Index' (duration: 1.062467ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:27:41.702398Z","caller":"traceutil/trace.go:171","msg":"trace[906697025] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"257.986982ms","start":"2026-04-14T07:27:41.444354Z","end":"2026-04-14T07:27:41.702341Z","steps":["trace[906697025] 'process raft request' (duration: 257.178462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:41.702638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.964836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:27:41.70269Z","caller":"traceutil/trace.go:171","msg":"trace[1114896400] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:668; }","duration":"253.057079ms","start":"2026-04-14T07:27:41.449619Z","end":"2026-04-14T07:27:41.702676Z","steps":["trace[1114896400] 'agreement among raft nodes before linearized reading' (duration: 252.887425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:41.702732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.078419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T07:27:41.702834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.058392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:27:41.702874Z","caller":"traceutil/trace.go:171","msg":"trace[943754945] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:668; }","duration":"213.096932ms","start":"2026-04-14T07:27:41.48976Z","end":"2026-04-14T07:27:41.702857Z","steps":["trace[943754945] 'agreement among raft nodes before linearized reading' (duration: 213.053701ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:41.702872Z","caller":"traceutil/trace.go:171","msg":"trace[1555682158] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:668; }","duration":"253.259935ms","start":"2026-04-14T07:27:41.449572Z","end":"2026-04-14T07:27:41.702832Z","steps":["trace[1555682158] 'agreement among raft nodes before linearized reading' (duration: 252.886925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:41.702894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.909697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T07:27:41.702789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.209784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:27:41.702993Z","caller":"traceutil/trace.go:171","msg":"trace[536948319] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:668; }","duration":"213.05069ms","start":"2026-04-14T07:27:41.489922Z","end":"2026-04-14T07:27:41.702973Z","steps":["trace[536948319] 'agreement among raft nodes before linearized reading' (duration: 212.831374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:41.703015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.849335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:27:41.703027Z","caller":"traceutil/trace.go:171","msg":"trace[1432943885] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:668; }","duration":"213.447911ms","start":"2026-04-14T07:27:41.489541Z","end":"2026-04-14T07:27:41.702988Z","steps":["trace[1432943885] 'agreement among raft nodes before linearized reading' (duration: 213.179263ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:41.703067Z","caller":"traceutil/trace.go:171","msg":"trace[64680851] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:668; }","duration":"232.907787ms","start":"2026-04-14T07:27:41.470147Z","end":"2026-04-14T07:27:41.703055Z","steps":["trace[64680851] 'agreement among raft nodes before linearized reading' (duration: 232.798475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:41.703135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.967101ms","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-04-14T07:27:41.703159Z","caller":"traceutil/trace.go:171","msg":"trace[1368116017] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:668; }","duration":"127.015803ms","start":"2026-04-14T07:27:41.576136Z","end":"2026-04-14T07:27:41.703151Z","steps":["trace[1368116017] 'agreement among raft nodes before linearized reading' (duration: 126.976622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:41.70322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.148994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T07:27:41.703258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.737698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:27:41.703281Z","caller":"traceutil/trace.go:171","msg":"trace[1271245256] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:668; }","duration":"187.79602ms","start":"2026-04-14T07:27:41.515477Z","end":"2026-04-14T07:27:41.703273Z","steps":["trace[1271245256] 'agreement among raft nodes before linearized reading' (duration: 187.757339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:41.703283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.365006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:27:41.70328Z","caller":"traceutil/trace.go:171","msg":"trace[1108652939] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:668; }","duration":"189.250396ms","start":"2026-04-14T07:27:41.514009Z","end":"2026-04-14T07:27:41.703259Z","steps":["trace[1108652939] 'agreement among raft nodes before linearized reading' (duration: 189.101933ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:41.703306Z","caller":"traceutil/trace.go:171","msg":"trace[842767627] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:668; }","duration":"126.411146ms","start":"2026-04-14T07:27:41.576887Z","end":"2026-04-14T07:27:41.703298Z","steps":["trace[842767627] 'agreement among raft nodes before linearized reading' (duration: 126.373635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:41.702771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.692361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T07:27:41.703334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.180964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:27:41.703368Z","caller":"traceutil/trace.go:171","msg":"trace[1287486181] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:668; }","duration":"189.311077ms","start":"2026-04-14T07:27:41.514035Z","end":"2026-04-14T07:27:41.703347Z","steps":["trace[1287486181] 'agreement among raft nodes before linearized reading' (duration: 188.65918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:41.703398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.084135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T07:27:41.703397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.543691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:27:41.703419Z","caller":"traceutil/trace.go:171","msg":"trace[1847929351] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:668; }","duration":"233.289736ms","start":"2026-04-14T07:27:41.470117Z","end":"2026-04-14T07:27:41.703363Z","steps":["trace[1847929351] 'agreement among raft nodes before linearized reading' (duration: 233.182023ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:41.703458Z","caller":"traceutil/trace.go:171","msg":"trace[856299392] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:668; }","duration":"253.634694ms","start":"2026-04-14T07:27:41.449803Z","end":"2026-04-14T07:27:41.703438Z","steps":["trace[856299392] 'agreement among raft nodes before linearized reading' (duration: 253.544181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:41.70348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.746168ms","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":"info","ts":"2026-04-14T07:27:41.703502Z","caller":"traceutil/trace.go:171","msg":"trace[552412259] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:668; }","duration":"233.797549ms","start":"2026-04-14T07:27:41.469699Z","end":"2026-04-14T07:27:41.703496Z","steps":["trace[552412259] 'agreement among raft nodes before linearized reading' (duration: 233.763928ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:41.703419Z","caller":"traceutil/trace.go:171","msg":"trace[827808037] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:668; }","duration":"127.106305ms","start":"2026-04-14T07:27:41.576306Z","end":"2026-04-14T07:27:41.703412Z","steps":["trace[827808037] 'agreement among raft nodes before linearized reading' (duration: 127.068584ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:43.439564Z","caller":"traceutil/trace.go:171","msg":"trace[622440855] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"160.238661ms","start":"2026-04-14T07:27:43.279286Z","end":"2026-04-14T07:27:43.439524Z","steps":["trace[622440855] 'process raft request' (duration: 157.616707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:44.647013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303070667281892,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T07:27:44.953428Z","caller":"traceutil/trace.go:171","msg":"trace[1866714436] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"957.90484ms","start":"2026-04-14T07:27:43.995478Z","end":"2026-04-14T07:27:44.953383Z","steps":["trace[1866714436] 'process raft request' (duration: 957.578142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:44.953638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:43.995442Z","time spent":"958.091905ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:27:45.530796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.447142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T07:27:45.530934Z","caller":"traceutil/trace.go:171","msg":"trace[1393611620] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:716; }","duration":"1.384874661s","start":"2026-04-14T07:27:44.146039Z","end":"2026-04-14T07:27:45.530914Z","steps":["trace[1393611620] 'read index received' (duration: 807.144035ms)","trace[1393611620] 'applied index is now lower than readState.Index' (duration: 577.729316ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:27:45.531028Z","caller":"traceutil/trace.go:171","msg":"trace[1344870721] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"917.065589ms","start":"2026-04-14T07:27:44.613923Z","end":"2026-04-14T07:27:45.530988Z","steps":["trace[1344870721] 'process raft request' (duration: 407.32526ms)","trace[1344870721] 'compare' (duration: 509.329519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:27:45.531117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385088606s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-14T07:27:45.53118Z","caller":"traceutil/trace.go:171","msg":"trace[1188358380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:692; }","duration":"1.385118287s","start":"2026-04-14T07:27:44.146018Z","end":"2026-04-14T07:27:45.531137Z","steps":["trace[1188358380] 'agreement among raft nodes before linearized reading' (duration: 1.384993874s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:45.531221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:44.145936Z","time spent":"1.385275211s","remote":"127.0.0.1:54468","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-04-14T07:27:45.531278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:44.613899Z","time spent":"917.209553ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:27:45.531582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164337154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6p69v\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-14T07:27:45.531626Z","caller":"traceutil/trace.go:171","msg":"trace[468901121] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6p69v; range_end:; response_count:1; response_revision:692; }","duration":"1.164423867s","start":"2026-04-14T07:27:44.36719Z","end":"2026-04-14T07:27:45.531614Z","steps":["trace[468901121] 'agreement among raft nodes before linearized reading' (duration: 1.164273693s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:45.531656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:44.367167Z","time spent":"1.164483337s","remote":"127.0.0.1:54360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-6p69v\" "} {"level":"warn","ts":"2026-04-14T07:27:45.531581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383409335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-14T07:27:45.53173Z","caller":"traceutil/trace.go:171","msg":"trace[1744461200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"1.38359773s","start":"2026-04-14T07:27:44.14812Z","end":"2026-04-14T07:27:45.531718Z","steps":["trace[1744461200] 'agreement among raft nodes before linearized reading' (duration: 1.383293133s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:45.531775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:44.148108Z","time spent":"1.383655871s","remote":"127.0.0.1:54468","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-04-14T07:27:45.531851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.561222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-14T07:27:45.531915Z","caller":"traceutil/trace.go:171","msg":"trace[2085088723] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:692; }","duration":"209.673235ms","start":"2026-04-14T07:27:45.322225Z","end":"2026-04-14T07:27:45.531898Z","steps":["trace[2085088723] 'agreement among raft nodes before linearized reading' (duration: 209.4791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:45.531863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276468303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:27:45.532047Z","caller":"traceutil/trace.go:171","msg":"trace[1923103422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"1.276665309s","start":"2026-04-14T07:27:44.255337Z","end":"2026-04-14T07:27:45.532003Z","steps":["trace[1923103422] 'agreement among raft nodes before linearized reading' (duration: 1.276363542s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:27:45.53274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:27:44.255311Z","time spent":"1.277414068s","remote":"127.0.0.1:54182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T07:27:45.646167Z","caller":"traceutil/trace.go:171","msg":"trace[864469095] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"109.339561ms","start":"2026-04-14T07:27:45.536808Z","end":"2026-04-14T07:27:45.646148Z","steps":["trace[864469095] 'process raft request' (duration: 109.142006ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:45.6463Z","caller":"traceutil/trace.go:171","msg":"trace[1549584048] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:719; }","duration":"104.841267ms","start":"2026-04-14T07:27:45.541451Z","end":"2026-04-14T07:27:45.646292Z","steps":["trace[1549584048] 'read index received' (duration: 70.355137ms)","trace[1549584048] 'applied index is now lower than readState.Index' (duration: 34.48537ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:27:45.64638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.92293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:27:45.646403Z","caller":"traceutil/trace.go:171","msg":"trace[1823903587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:695; }","duration":"104.984811ms","start":"2026-04-14T07:27:45.541412Z","end":"2026-04-14T07:27:45.646397Z","steps":["trace[1823903587] 'agreement among raft nodes before linearized reading' (duration: 104.906479ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:45.646458Z","caller":"traceutil/trace.go:171","msg":"trace[830797527] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"109.069013ms","start":"2026-04-14T07:27:45.537174Z","end":"2026-04-14T07:27:45.646243Z","steps":["trace[830797527] 'process raft request' (duration: 108.879128ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:27:45.64651Z","caller":"traceutil/trace.go:171","msg":"trace[380757635] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"101.440463ms","start":"2026-04-14T07:27:45.545033Z","end":"2026-04-14T07:27:45.646474Z","steps":["trace[380757635] 'process raft request' (duration: 101.054633ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:28:05.488648Z","caller":"traceutil/trace.go:171","msg":"trace[1609948855] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"430.191565ms","start":"2026-04-14T07:28:05.058428Z","end":"2026-04-14T07:28:05.488619Z","steps":["trace[1609948855] 'read index received' (duration: 429.936049ms)","trace[1609948855] 'applied index is now lower than readState.Index' (duration: 254.686µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:28:05.488908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.462661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T07:28:05.488956Z","caller":"traceutil/trace.go:171","msg":"trace[1379988584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:816; }","duration":"430.529693ms","start":"2026-04-14T07:28:05.058411Z","end":"2026-04-14T07:28:05.488941Z","steps":["trace[1379988584] 'agreement among raft nodes before linearized reading' (duration: 430.349069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:28:05.489002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:28:05.05835Z","time spent":"430.642286ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T07:28:05.489127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.962663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-14T07:28:05.489211Z","caller":"traceutil/trace.go:171","msg":"trace[1414038494] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:816; }","duration":"166.086796ms","start":"2026-04-14T07:28:05.323101Z","end":"2026-04-14T07:28:05.489187Z","steps":["trace[1414038494] 'agreement among raft nodes before linearized reading' (duration: 165.881611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:28:05.489101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.383128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:28:05.489144Z","caller":"traceutil/trace.go:171","msg":"trace[312856518] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"942.147593ms","start":"2026-04-14T07:28:04.546949Z","end":"2026-04-14T07:28:05.489097Z","steps":["trace[312856518] 'process raft request' (duration: 941.512137ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:28:05.489278Z","caller":"traceutil/trace.go:171","msg":"trace[317230416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"232.641675ms","start":"2026-04-14T07:28:05.256615Z","end":"2026-04-14T07:28:05.489256Z","steps":["trace[317230416] 'agreement among raft nodes before linearized reading' (duration: 232.374028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:28:05.489528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:28:04.546931Z","time spent":"942.41798ms","remote":"127.0.0.1:54360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:28:05.762016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.946238ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-14T07:28:05.762143Z","caller":"traceutil/trace.go:171","msg":"trace[720902697] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"270.926577ms","start":"2026-04-14T07:28:05.491186Z","end":"2026-04-14T07:28:05.762112Z","steps":["trace[720902697] 'read index received' (duration: 90.887527ms)","trace[720902697] 'applied index is now lower than readState.Index' (duration: 180.03694ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:28:05.762265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.062441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:28:05.762302Z","caller":"traceutil/trace.go:171","msg":"trace[1779737996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"271.139562ms","start":"2026-04-14T07:28:05.491149Z","end":"2026-04-14T07:28:05.762289Z","steps":["trace[1779737996] 'agreement among raft nodes before linearized reading' (duration: 271.05116ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:28:05.767983Z","caller":"traceutil/trace.go:171","msg":"trace[1347795229] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"267.593985ms","start":"2026-04-14T07:28:05.500367Z","end":"2026-04-14T07:28:05.767961Z","steps":["trace[1347795229] 'process raft request' (duration: 267.431651ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:28:39.925457Z","caller":"traceutil/trace.go:171","msg":"trace[628747270] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"121.889209ms","start":"2026-04-14T07:28:39.803537Z","end":"2026-04-14T07:28:39.925426Z","steps":["trace[628747270] 'process raft request' (duration: 121.727196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:28:40.710853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.107378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-14T07:28:40.710988Z","caller":"traceutil/trace.go:171","msg":"trace[1948806410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1030; }","duration":"492.327224ms","start":"2026-04-14T07:28:40.21863Z","end":"2026-04-14T07:28:40.710957Z","steps":["trace[1948806410] 'range keys from in-memory index tree' (duration: 491.883293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:28:40.711039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:28:40.218604Z","time spent":"492.422566ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T07:28:40.711069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.65401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-14T07:28:40.711055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.811375ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T07:28:40.711168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.85794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-14T07:28:40.711236Z","caller":"traceutil/trace.go:171","msg":"trace[1505319173] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1030; }","duration":"254.014421ms","start":"2026-04-14T07:28:40.457192Z","end":"2026-04-14T07:28:40.711207Z","steps":["trace[1505319173] 'range keys from in-memory index tree' (duration: 253.789744ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:28:40.711272Z","caller":"traceutil/trace.go:171","msg":"trace[695188981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1030; }","duration":"526.016544ms","start":"2026-04-14T07:28:40.185227Z","end":"2026-04-14T07:28:40.711243Z","steps":["trace[695188981] 'range keys from in-memory index tree' (duration: 525.562522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:28:40.71132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:28:40.185195Z","time spent":"526.116016ms","remote":"127.0.0.1:54468","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-04-14T07:28:40.711349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.67071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-14T07:28:40.711403Z","caller":"traceutil/trace.go:171","msg":"trace[77869081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1030; }","duration":"465.766482ms","start":"2026-04-14T07:28:40.245624Z","end":"2026-04-14T07:28:40.71139Z","steps":["trace[77869081] 'range keys from in-memory index tree' (duration: 465.407924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:28:40.711441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:28:40.245594Z","time spent":"465.838084ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-14T07:28:40.711169Z","caller":"traceutil/trace.go:171","msg":"trace[1137852659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1030; }","duration":"676.796495ms","start":"2026-04-14T07:28:40.034333Z","end":"2026-04-14T07:28:40.711129Z","steps":["trace[1137852659] 'range keys from in-memory index tree' (duration: 676.386263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:28:40.711503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:28:40.034309Z","time spent":"677.184264ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T07:28:40.711063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.149809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-14T07:28:40.711568Z","caller":"traceutil/trace.go:171","msg":"trace[1135017486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1030; }","duration":"483.712493ms","start":"2026-04-14T07:28:40.227843Z","end":"2026-04-14T07:28:40.711556Z","steps":["trace[1135017486] 'range keys from in-memory index tree' (duration: 482.867012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:28:40.711605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:28:40.227816Z","time spent":"483.777234ms","remote":"127.0.0.1:54468","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-04-14T07:28:40.970452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.804452ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-14T07:28:40.973985Z","caller":"traceutil/trace.go:171","msg":"trace[900301283] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"477.099029ms","start":"2026-04-14T07:28:40.496858Z","end":"2026-04-14T07:28:40.973957Z","steps":["trace[900301283] 'process raft request' (duration: 476.364031ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:28:40.97432Z","caller":"traceutil/trace.go:171","msg":"trace[1035282111] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"257.937657ms","start":"2026-04-14T07:28:40.716341Z","end":"2026-04-14T07:28:40.974279Z","steps":["trace[1035282111] 'process raft request' (duration: 257.538977ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:28:40.974533Z","caller":"traceutil/trace.go:171","msg":"trace[1543768842] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"257.652739ms","start":"2026-04-14T07:28:40.716664Z","end":"2026-04-14T07:28:40.974317Z","steps":["trace[1543768842] 'process raft request' (duration: 257.529276ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:28:40.974776Z","caller":"traceutil/trace.go:171","msg":"trace[930906087] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"257.953367ms","start":"2026-04-14T07:28:40.716812Z","end":"2026-04-14T07:28:40.974765Z","steps":["trace[930906087] 'process raft request' (duration: 257.466834ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:28:40.974959Z","caller":"traceutil/trace.go:171","msg":"trace[2124550804] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"257.816854ms","start":"2026-04-14T07:28:40.717127Z","end":"2026-04-14T07:28:40.974944Z","steps":["trace[2124550804] 'process raft request' (duration: 257.729032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:28:40.974318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:28:40.496822Z","time spent":"477.242523ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:28:40.975386Z","caller":"traceutil/trace.go:171","msg":"trace[769577178] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"258.415098ms","start":"2026-04-14T07:28:40.716955Z","end":"2026-04-14T07:28:40.97537Z","steps":["trace[769577178] 'process raft request' (duration: 257.751442ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:03.285101Z","caller":"traceutil/trace.go:171","msg":"trace[1433272520] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"108.843929ms","start":"2026-04-14T07:29:03.176233Z","end":"2026-04-14T07:29:03.285077Z","steps":["trace[1433272520] 'read index received' (duration: 108.678565ms)","trace[1433272520] 'applied index is now lower than readState.Index' (duration: 163.374µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:29:03.285185Z","caller":"traceutil/trace.go:171","msg":"trace[847402743] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"156.470701ms","start":"2026-04-14T07:29:03.128695Z","end":"2026-04-14T07:29:03.285166Z","steps":["trace[847402743] 'process raft request' (duration: 156.231795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:03.285337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.022352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-14T07:29:03.285365Z","caller":"traceutil/trace.go:171","msg":"trace[911850072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1104; }","duration":"109.080205ms","start":"2026-04-14T07:29:03.176276Z","end":"2026-04-14T07:29:03.285356Z","steps":["trace[911850072] 'agreement among raft nodes before linearized reading' (duration: 108.967072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:03.285501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.253739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T07:29:03.285619Z","caller":"traceutil/trace.go:171","msg":"trace[1030285901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1104; }","duration":"109.414183ms","start":"2026-04-14T07:29:03.176194Z","end":"2026-04-14T07:29:03.285608Z","steps":["trace[1030285901] 'agreement among raft nodes before linearized reading' (duration: 108.973452ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:09.61016Z","caller":"traceutil/trace.go:171","msg":"trace[341566263] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"295.957624ms","start":"2026-04-14T07:29:09.314177Z","end":"2026-04-14T07:29:09.610134Z","steps":["trace[341566263] 'process raft request' (duration: 295.765538ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:10.039282Z","caller":"traceutil/trace.go:171","msg":"trace[491493192] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1169; }","duration":"710.921513ms","start":"2026-04-14T07:29:09.328307Z","end":"2026-04-14T07:29:10.039229Z","steps":["trace[491493192] 'read index received' (duration: 281.628716ms)","trace[491493192] 'applied index is now lower than readState.Index' (duration: 429.291407ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:29:10.039399Z","caller":"traceutil/trace.go:171","msg":"trace[1368114108] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"724.153623ms","start":"2026-04-14T07:29:09.315195Z","end":"2026-04-14T07:29:10.039348Z","steps":["trace[1368114108] 'process raft request' (duration: 696.512644ms)","trace[1368114108] 'compare' (duration: 26.90266ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:29:10.039566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.297118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-14T07:29:10.039569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:09.315162Z","time spent":"724.312167ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:29:10.039611Z","caller":"traceutil/trace.go:171","msg":"trace[199288705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1125; }","duration":"698.39017ms","start":"2026-04-14T07:29:09.34121Z","end":"2026-04-14T07:29:10.039601Z","steps":["trace[199288705] 'agreement among raft nodes before linearized reading' (duration: 698.201285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:10.039651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:09.341197Z","time spent":"698.446722ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T07:29:10.039864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.093166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T07:29:10.039998Z","caller":"traceutil/trace.go:171","msg":"trace[779148515] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1125; }","duration":"607.28558ms","start":"2026-04-14T07:29:09.432693Z","end":"2026-04-14T07:29:10.039978Z","steps":["trace[779148515] 'agreement among raft nodes before linearized reading' (duration: 606.86722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:10.04008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:09.432666Z","time spent":"607.401374ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T07:29:10.039839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.517928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-14T07:29:10.040402Z","caller":"traceutil/trace.go:171","msg":"trace[832055351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1125; }","duration":"712.094912ms","start":"2026-04-14T07:29:09.328238Z","end":"2026-04-14T07:29:10.040333Z","steps":["trace[832055351] 'agreement among raft nodes before linearized reading' (duration: 711.135238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:10.040566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:09.328196Z","time spent":"712.301737ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T07:29:11.566009Z","caller":"traceutil/trace.go:171","msg":"trace[158093393] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"280.442097ms","start":"2026-04-14T07:29:11.285528Z","end":"2026-04-14T07:29:11.56597Z","steps":["trace[158093393] 'process raft request' (duration: 280.236652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:11.881882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.296794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T07:29:11.881982Z","caller":"traceutil/trace.go:171","msg":"trace[1766265766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1130; }","duration":"266.472189ms","start":"2026-04-14T07:29:11.615488Z","end":"2026-04-14T07:29:11.88196Z","steps":["trace[1766265766] 'range keys from in-memory index tree' (duration: 266.042629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:25.737034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.549078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T07:29:25.737136Z","caller":"traceutil/trace.go:171","msg":"trace[724065802] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"386.90313ms","start":"2026-04-14T07:29:25.350216Z","end":"2026-04-14T07:29:25.737119Z","steps":["trace[724065802] 'process raft request' (duration: 33.192927ms)","trace[724065802] 'compare' (duration: 353.436416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:29:25.73719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:25.35019Z","time spent":"386.974011ms","remote":"127.0.0.1:54200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:29:41.717875Z","caller":"traceutil/trace.go:171","msg":"trace[1564836760] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"161.627587ms","start":"2026-04-14T07:29:41.556221Z","end":"2026-04-14T07:29:41.717848Z","steps":["trace[1564836760] 'read index received' (duration: 147.176237ms)","trace[1564836760] 'applied index is now lower than readState.Index' (duration: 14.45045ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:29:41.718034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.788291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T07:29:41.718072Z","caller":"traceutil/trace.go:171","msg":"trace[702991355] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1295; }","duration":"161.870233ms","start":"2026-04-14T07:29:41.556187Z","end":"2026-04-14T07:29:41.718057Z","steps":["trace[702991355] 'agreement among raft nodes before linearized reading' (duration: 161.776841ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:41.718063Z","caller":"traceutil/trace.go:171","msg":"trace[1671983941] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"191.593423ms","start":"2026-04-14T07:29:41.526439Z","end":"2026-04-14T07:29:41.718033Z","steps":["trace[1671983941] 'process raft request' (duration: 177.01643ms)","trace[1671983941] 'compare' (duration: 13.938587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:29:42.486524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.054946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:29:42.4866Z","caller":"traceutil/trace.go:171","msg":"trace[1970251306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"648.151738ms","start":"2026-04-14T07:29:41.838433Z","end":"2026-04-14T07:29:42.486585Z","steps":["trace[1970251306] 'range keys from in-memory index tree' (duration: 647.981115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:42.486639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:41.83837Z","time spent":"648.260191ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T07:29:42.486926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.434369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-pgx8k\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-04-14T07:29:42.486979Z","caller":"traceutil/trace.go:171","msg":"trace[216184048] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-pgx8k; range_end:; response_count:1; response_revision:1295; }","duration":"596.522661ms","start":"2026-04-14T07:29:41.890442Z","end":"2026-04-14T07:29:42.486965Z","steps":["trace[216184048] 'range keys from in-memory index tree' (duration: 596.299136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:42.487018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:41.890422Z","time spent":"596.586073ms","remote":"127.0.0.1:54360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11081,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-pgx8k\" "} {"level":"warn","ts":"2026-04-14T07:29:42.487363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.135797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-14T07:29:42.487415Z","caller":"traceutil/trace.go:171","msg":"trace[1763255725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1295; }","duration":"373.225069ms","start":"2026-04-14T07:29:42.114177Z","end":"2026-04-14T07:29:42.487402Z","steps":["trace[1763255725] 'range keys from in-memory index tree' (duration: 373.017085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:42.48746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:42.114158Z","time spent":"373.287091ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T07:29:42.641117Z","caller":"traceutil/trace.go:171","msg":"trace[2035943297] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"466.423671ms","start":"2026-04-14T07:29:42.174667Z","end":"2026-04-14T07:29:42.641091Z","steps":["trace[2035943297] 'process raft request' (duration: 466.261897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:42.641282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:42.174644Z","time spent":"466.559785ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:29:42.6413Z","caller":"traceutil/trace.go:171","msg":"trace[1003871077] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1351; }","duration":"386.420578ms","start":"2026-04-14T07:29:42.254839Z","end":"2026-04-14T07:29:42.641259Z","steps":["trace[1003871077] 'read index received' (duration: 386.404397ms)","trace[1003871077] 'applied index is now lower than readState.Index' (duration: 13.291µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:29:42.641496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.636154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:29:42.641601Z","caller":"traceutil/trace.go:171","msg":"trace[999314997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1296; }","duration":"386.789638ms","start":"2026-04-14T07:29:42.254782Z","end":"2026-04-14T07:29:42.641571Z","steps":["trace[999314997] 'agreement among raft nodes before linearized reading' (duration: 386.606083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:42.64165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:42.254763Z","time spent":"386.878199ms","remote":"127.0.0.1:54182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T07:29:42.699553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.185123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T07:29:42.69964Z","caller":"traceutil/trace.go:171","msg":"trace[15570124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1299; }","duration":"433.303555ms","start":"2026-04-14T07:29:42.266317Z","end":"2026-04-14T07:29:42.699621Z","steps":["trace[15570124] 'agreement among raft nodes before linearized reading' (duration: 433.09551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:42.699682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:42.266303Z","time spent":"433.370707ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-14T07:29:42.700015Z","caller":"traceutil/trace.go:171","msg":"trace[1081047639] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"373.909096ms","start":"2026-04-14T07:29:42.326093Z","end":"2026-04-14T07:29:42.700002Z","steps":["trace[1081047639] 'process raft request' (duration: 373.039534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:42.700112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:42.326066Z","time spent":"373.987689ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:29:42.700247Z","caller":"traceutil/trace.go:171","msg":"trace[805163656] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"207.410998ms","start":"2026-04-14T07:29:42.492826Z","end":"2026-04-14T07:29:42.700237Z","steps":["trace[805163656] 'process raft request' (duration: 206.458984ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:42.70046Z","caller":"traceutil/trace.go:171","msg":"trace[1072559757] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"197.288826ms","start":"2026-04-14T07:29:42.503162Z","end":"2026-04-14T07:29:42.700451Z","steps":["trace[1072559757] 'process raft request' (duration: 196.179578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:42.70064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.982567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T07:29:42.700674Z","caller":"traceutil/trace.go:171","msg":"trace[1047508798] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1299; }","duration":"213.037698ms","start":"2026-04-14T07:29:42.487628Z","end":"2026-04-14T07:29:42.700666Z","steps":["trace[1047508798] 'agreement among raft nodes before linearized reading' (duration: 212.950286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:42.700828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.79564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-14T07:29:42.700861Z","caller":"traceutil/trace.go:171","msg":"trace[1227577920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1299; }","duration":"226.862022ms","start":"2026-04-14T07:29:42.473991Z","end":"2026-04-14T07:29:42.700853Z","steps":["trace[1227577920] 'agreement among raft nodes before linearized reading' (duration: 226.78317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:42.701008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.265376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T07:29:42.70104Z","caller":"traceutil/trace.go:171","msg":"trace[587632917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1299; }","duration":"254.330807ms","start":"2026-04-14T07:29:42.446701Z","end":"2026-04-14T07:29:42.701032Z","steps":["trace[587632917] 'agreement among raft nodes before linearized reading' (duration: 254.256595ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:42.866186Z","caller":"traceutil/trace.go:171","msg":"trace[1146816508] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1354; }","duration":"145.826274ms","start":"2026-04-14T07:29:42.720325Z","end":"2026-04-14T07:29:42.866152Z","steps":["trace[1146816508] 'read index received' (duration: 30.924261ms)","trace[1146816508] 'applied index is now lower than readState.Index' (duration: 114.900523ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:29:42.866382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.029339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:29:42.866433Z","caller":"traceutil/trace.go:171","msg":"trace[1690159296] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1306; }","duration":"146.151511ms","start":"2026-04-14T07:29:42.720266Z","end":"2026-04-14T07:29:42.866417Z","steps":["trace[1690159296] 'agreement among raft nodes before linearized reading' (duration: 146.018528ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:42.86683Z","caller":"traceutil/trace.go:171","msg":"trace[2126359045] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"157.136775ms","start":"2026-04-14T07:29:42.709676Z","end":"2026-04-14T07:29:42.866813Z","steps":["trace[2126359045] 'process raft request' (duration: 155.972437ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:42.8671Z","caller":"traceutil/trace.go:171","msg":"trace[739774339] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"157.079564ms","start":"2026-04-14T07:29:42.71001Z","end":"2026-04-14T07:29:42.867089Z","steps":["trace[739774339] 'process raft request' (duration: 155.861244ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:42.867256Z","caller":"traceutil/trace.go:171","msg":"trace[80126737] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"157.180737ms","start":"2026-04-14T07:29:42.710064Z","end":"2026-04-14T07:29:42.867245Z","steps":["trace[80126737] 'process raft request' (duration: 155.868335ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:42.867469Z","caller":"traceutil/trace.go:171","msg":"trace[1671678994] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"157.33914ms","start":"2026-04-14T07:29:42.710098Z","end":"2026-04-14T07:29:42.867437Z","steps":["trace[1671678994] 'process raft request' (duration: 155.877855ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:42.867789Z","caller":"traceutil/trace.go:171","msg":"trace[1273208807] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"157.31803ms","start":"2026-04-14T07:29:42.710458Z","end":"2026-04-14T07:29:42.867776Z","steps":["trace[1273208807] 'process raft request' (duration: 155.559907ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:42.868597Z","caller":"traceutil/trace.go:171","msg":"trace[1064108406] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"152.134961ms","start":"2026-04-14T07:29:42.716379Z","end":"2026-04-14T07:29:42.868514Z","steps":["trace[1064108406] 'process raft request' (duration: 149.70685ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:45.200639Z","caller":"traceutil/trace.go:171","msg":"trace[31590225] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1362; }","duration":"491.167957ms","start":"2026-04-14T07:29:44.709446Z","end":"2026-04-14T07:29:45.200614Z","steps":["trace[31590225] 'read index received' (duration: 490.940161ms)","trace[31590225] 'applied index is now lower than readState.Index' (duration: 227.066µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:29:45.200897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.042968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-14T07:29:45.200926Z","caller":"traceutil/trace.go:171","msg":"trace[1904726284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1308; }","duration":"445.12617ms","start":"2026-04-14T07:29:44.755792Z","end":"2026-04-14T07:29:45.200918Z","steps":["trace[1904726284] 'agreement among raft nodes before linearized reading' (duration: 444.943546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:45.200959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:44.755781Z","time spent":"445.168871ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T07:29:45.200907Z","caller":"traceutil/trace.go:171","msg":"trace[786653103] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"493.568068ms","start":"2026-04-14T07:29:44.70728Z","end":"2026-04-14T07:29:45.200848Z","steps":["trace[786653103] 'process raft request' (duration: 493.178128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:45.201069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.660044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-14T07:29:45.201047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.467925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T07:29:45.201109Z","caller":"traceutil/trace.go:171","msg":"trace[1911531835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1308; }","duration":"327.734956ms","start":"2026-04-14T07:29:44.873363Z","end":"2026-04-14T07:29:45.201098Z","steps":["trace[1911531835] 'agreement among raft nodes before linearized reading' (duration: 327.550871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:45.201135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:44.873346Z","time spent":"327.784047ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T07:29:45.201125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:44.707251Z","time spent":"493.770812ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:29:45.201138Z","caller":"traceutil/trace.go:171","msg":"trace[1589385174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1308; }","duration":"324.625838ms","start":"2026-04-14T07:29:44.87649Z","end":"2026-04-14T07:29:45.201116Z","steps":["trace[1589385174] 'agreement among raft nodes before linearized reading' (duration: 324.317711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:45.201192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:44.876457Z","time spent":"324.725391ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T07:29:45.201184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.69046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T07:29:45.201273Z","caller":"traceutil/trace.go:171","msg":"trace[1048653000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1308; }","duration":"324.872865ms","start":"2026-04-14T07:29:44.87638Z","end":"2026-04-14T07:29:45.201253Z","steps":["trace[1048653000] 'agreement among raft nodes before linearized reading' (duration: 324.501496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:45.201323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:44.876359Z","time spent":"324.955206ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T07:29:45.201349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.895695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-14T07:29:45.201398Z","caller":"traceutil/trace.go:171","msg":"trace[1214166594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1308; }","duration":"491.985098ms","start":"2026-04-14T07:29:44.709397Z","end":"2026-04-14T07:29:45.201382Z","steps":["trace[1214166594] 'agreement among raft nodes before linearized reading' (duration: 491.352722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:45.201441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:44.709376Z","time spent":"492.049689ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T07:29:45.395371Z","caller":"traceutil/trace.go:171","msg":"trace[1096360294] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"188.635121ms","start":"2026-04-14T07:29:45.206696Z","end":"2026-04-14T07:29:45.395331Z","steps":["trace[1096360294] 'process raft request' (duration: 121.200281ms)","trace[1096360294] 'compare' (duration: 67.203604ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:29:45.395627Z","caller":"traceutil/trace.go:171","msg":"trace[720337287] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"188.898987ms","start":"2026-04-14T07:29:45.206707Z","end":"2026-04-14T07:29:45.395606Z","steps":["trace[720337287] 'process raft request' (duration: 188.560658ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:45.395913Z","caller":"traceutil/trace.go:171","msg":"trace[1857230501] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"189.03001ms","start":"2026-04-14T07:29:45.206875Z","end":"2026-04-14T07:29:45.395905Z","steps":["trace[1857230501] 'process raft request' (duration: 188.63685ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:45.396081Z","caller":"traceutil/trace.go:171","msg":"trace[1802957891] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"189.053731ms","start":"2026-04-14T07:29:45.207006Z","end":"2026-04-14T07:29:45.39606Z","steps":["trace[1802957891] 'process raft request' (duration: 188.860596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:46.103398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.758644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T07:29:46.103518Z","caller":"traceutil/trace.go:171","msg":"trace[194180772] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"504.063967ms","start":"2026-04-14T07:29:45.599435Z","end":"2026-04-14T07:29:46.103498Z","steps":["trace[194180772] 'process raft request' (duration: 280.128959ms)","trace[194180772] 'compare' (duration: 223.639351ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:29:46.103594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:45.599421Z","time spent":"504.15042ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T07:29:46.122036Z","caller":"traceutil/trace.go:171","msg":"trace[643513434] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"159.799202ms","start":"2026-04-14T07:29:45.962125Z","end":"2026-04-14T07:29:46.121924Z","steps":["trace[643513434] 'process raft request' (duration: 159.646018ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:46.385409Z","caller":"traceutil/trace.go:171","msg":"trace[1053651567] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1373; }","duration":"219.741514ms","start":"2026-04-14T07:29:46.165643Z","end":"2026-04-14T07:29:46.385384Z","steps":["trace[1053651567] 'read index received' (duration: 29.172616ms)","trace[1053651567] 'applied index is now lower than readState.Index' (duration: 190.567868ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:29:46.385514Z","caller":"traceutil/trace.go:171","msg":"trace[589724926] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"255.533456ms","start":"2026-04-14T07:29:46.129936Z","end":"2026-04-14T07:29:46.38547Z","steps":["trace[589724926] 'process raft request' (duration: 255.350312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:46.385642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.611635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T07:29:46.385605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.917659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T07:29:46.385684Z","caller":"traceutil/trace.go:171","msg":"trace[880002574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"128.706637ms","start":"2026-04-14T07:29:46.256966Z","end":"2026-04-14T07:29:46.385673Z","steps":["trace[880002574] 'agreement among raft nodes before linearized reading' (duration: 128.604585ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:46.385732Z","caller":"traceutil/trace.go:171","msg":"trace[1110234086] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1320; }","duration":"220.105974ms","start":"2026-04-14T07:29:46.16559Z","end":"2026-04-14T07:29:46.385696Z","steps":["trace[1110234086] 'agreement among raft nodes before linearized reading' (duration: 219.894939ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:46.385886Z","caller":"traceutil/trace.go:171","msg":"trace[1066885384] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"260.009299ms","start":"2026-04-14T07:29:46.125849Z","end":"2026-04-14T07:29:46.385858Z","steps":["trace[1066885384] 'process raft request' (duration: 232.027581ms)","trace[1066885384] 'compare' (duration: 27.087286ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:29:46.65208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.801864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:29:46.652201Z","caller":"traceutil/trace.go:171","msg":"trace[1515256151] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1320; }","duration":"131.994599ms","start":"2026-04-14T07:29:46.520177Z","end":"2026-04-14T07:29:46.652172Z","steps":["trace[1515256151] 'count revisions from in-memory index tree' (duration: 131.700401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:59.80073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303070667283441,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T07:29:59.802181Z","caller":"traceutil/trace.go:171","msg":"trace[1887202611] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1460; }","duration":"501.74004ms","start":"2026-04-14T07:29:59.300408Z","end":"2026-04-14T07:29:59.802148Z","steps":["trace[1887202611] 'read index received' (duration: 501.491434ms)","trace[1887202611] 'applied index is now lower than readState.Index' (duration: 246.806µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:29:59.802271Z","caller":"traceutil/trace.go:171","msg":"trace[916079210] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"525.02078ms","start":"2026-04-14T07:29:59.277214Z","end":"2026-04-14T07:29:59.802235Z","steps":["trace[916079210] 'process raft request' (duration: 524.704832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:59.802445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.052482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T07:29:59.802488Z","caller":"traceutil/trace.go:171","msg":"trace[714351556] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1403; }","duration":"348.189606ms","start":"2026-04-14T07:29:59.454288Z","end":"2026-04-14T07:29:59.802478Z","steps":["trace[714351556] 'agreement among raft nodes before linearized reading' (duration: 348.057332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:59.802534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:59.45426Z","time spent":"348.261027ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:29:59.802573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.862255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T07:29:59.802617Z","caller":"traceutil/trace.go:171","msg":"trace[2044389871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1403; }","duration":"253.979388ms","start":"2026-04-14T07:29:59.548626Z","end":"2026-04-14T07:29:59.802606Z","steps":["trace[2044389871] 'agreement among raft nodes before linearized reading' (duration: 253.744351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:59.802597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.147222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T07:29:59.802698Z","caller":"traceutil/trace.go:171","msg":"trace[1715732307] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1403; }","duration":"502.321576ms","start":"2026-04-14T07:29:59.300351Z","end":"2026-04-14T07:29:59.802672Z","steps":["trace[1715732307] 'agreement among raft nodes before linearized reading' (duration: 501.974766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:59.802692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.9971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-14T07:29:59.802701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.512323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-14T07:29:59.802757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:59.300335Z","time spent":"502.410998ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-14T07:29:59.802802Z","caller":"traceutil/trace.go:171","msg":"trace[80447500] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1403; }","duration":"301.665916ms","start":"2026-04-14T07:29:59.501111Z","end":"2026-04-14T07:29:59.802777Z","steps":["trace[80447500] 'agreement among raft nodes before linearized reading' (duration: 301.343669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:59.802858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:59.501093Z","time spent":"301.754918ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-14T07:29:59.802863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.405159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-14T07:29:59.802931Z","caller":"traceutil/trace.go:171","msg":"trace[806539602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1403; }","duration":"242.495532ms","start":"2026-04-14T07:29:59.560416Z","end":"2026-04-14T07:29:59.802911Z","steps":["trace[806539602] 'agreement among raft nodes before linearized reading' (duration: 242.361238ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:59.802772Z","caller":"traceutil/trace.go:171","msg":"trace[781845275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1403; }","duration":"240.084162ms","start":"2026-04-14T07:29:59.562668Z","end":"2026-04-14T07:29:59.802752Z","steps":["trace[781845275] 'agreement among raft nodes before linearized reading' (duration: 239.880767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:59.802656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.458809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T07:29:59.803142Z","caller":"traceutil/trace.go:171","msg":"trace[1359973769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1403; }","duration":"255.085875ms","start":"2026-04-14T07:29:59.548046Z","end":"2026-04-14T07:29:59.803132Z","steps":["trace[1359973769] 'agreement among raft nodes before linearized reading' (duration: 254.363647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:29:59.803287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:29:59.27718Z","time spent":"526.029836ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:29:59.932388Z","caller":"traceutil/trace.go:171","msg":"trace[1426146205] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"122.938543ms","start":"2026-04-14T07:29:59.809419Z","end":"2026-04-14T07:29:59.932358Z","steps":["trace[1426146205] 'process raft request' (duration: 119.19344ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:59.934529Z","caller":"traceutil/trace.go:171","msg":"trace[509709210] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"124.951454ms","start":"2026-04-14T07:29:59.809536Z","end":"2026-04-14T07:29:59.934487Z","steps":["trace[509709210] 'process raft request' (duration: 124.610816ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:59.934885Z","caller":"traceutil/trace.go:171","msg":"trace[1536385582] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"125.243752ms","start":"2026-04-14T07:29:59.809622Z","end":"2026-04-14T07:29:59.934865Z","steps":["trace[1536385582] 'process raft request' (duration: 124.77526ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:59.935379Z","caller":"traceutil/trace.go:171","msg":"trace[467537974] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"125.728543ms","start":"2026-04-14T07:29:59.809619Z","end":"2026-04-14T07:29:59.935347Z","steps":["trace[467537974] 'process raft request' (duration: 125.088868ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:59.935657Z","caller":"traceutil/trace.go:171","msg":"trace[736194093] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"125.922038ms","start":"2026-04-14T07:29:59.809722Z","end":"2026-04-14T07:29:59.935644Z","steps":["trace[736194093] 'process raft request' (duration: 125.665541ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:29:59.935785Z","caller":"traceutil/trace.go:171","msg":"trace[1383736425] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"123.844366ms","start":"2026-04-14T07:29:59.811931Z","end":"2026-04-14T07:29:59.935775Z","steps":["trace[1383736425] 'process raft request' (duration: 123.505808ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:30:02.161914Z","caller":"traceutil/trace.go:171","msg":"trace[1147147610] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"153.544645ms","start":"2026-04-14T07:30:02.008345Z","end":"2026-04-14T07:30:02.161889Z","steps":["trace[1147147610] 'process raft request' (duration: 153.450953ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:30:02.162697Z","caller":"traceutil/trace.go:171","msg":"trace[1950679584] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"154.95435ms","start":"2026-04-14T07:30:02.007706Z","end":"2026-04-14T07:30:02.16266Z","steps":["trace[1950679584] 'process raft request' (duration: 153.940695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:30:20.724037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.997323ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-14T07:30:38.326964Z","caller":"traceutil/trace.go:171","msg":"trace[2089016470] transaction","detail":"{read_only:false; response_revision:1580; number_of_response:1; }","duration":"101.897085ms","start":"2026-04-14T07:30:38.225031Z","end":"2026-04-14T07:30:38.326928Z","steps":["trace[2089016470] 'process raft request' (duration: 101.604658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:30:40.675641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.106148ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:30:40.675778Z","caller":"traceutil/trace.go:171","msg":"trace[1291158176] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1588; }","duration":"219.261542ms","start":"2026-04-14T07:30:40.456502Z","end":"2026-04-14T07:30:40.675764Z","steps":["trace[1291158176] 'range keys from in-memory index tree' (duration: 219.049727ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:30:40.676071Z","caller":"traceutil/trace.go:171","msg":"trace[1627590223] linearizableReadLoop","detail":"{readStateIndex:1655; appliedIndex:1654; }","duration":"345.187411ms","start":"2026-04-14T07:30:40.330767Z","end":"2026-04-14T07:30:40.675955Z","steps":["trace[1627590223] 'read index received' (duration: 343.534939ms)","trace[1627590223] 'applied index is now lower than readState.Index' (duration: 1.651562ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:30:40.676209Z","caller":"traceutil/trace.go:171","msg":"trace[1263265010] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"402.638689ms","start":"2026-04-14T07:30:40.273559Z","end":"2026-04-14T07:30:40.676198Z","steps":["trace[1263265010] 'process raft request' (duration: 400.790334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:30:40.676329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.546209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-14T07:30:40.676359Z","caller":"traceutil/trace.go:171","msg":"trace[280075708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1589; }","duration":"345.58841ms","start":"2026-04-14T07:30:40.330763Z","end":"2026-04-14T07:30:40.676351Z","steps":["trace[280075708] 'agreement among raft nodes before linearized reading' (duration: 345.465237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:30:40.676383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:30:40.330735Z","time spent":"345.641852ms","remote":"127.0.0.1:54468","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-04-14T07:30:40.676389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:30:40.273534Z","time spent":"402.768313ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:30:40.676727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.254038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-14T07:30:40.676749Z","caller":"traceutil/trace.go:171","msg":"trace[2056950194] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1589; }","duration":"216.30077ms","start":"2026-04-14T07:30:40.460442Z","end":"2026-04-14T07:30:40.676743Z","steps":["trace[2056950194] 'agreement among raft nodes before linearized reading' (duration: 216.212048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:30:40.676868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.080029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T07:30:40.676902Z","caller":"traceutil/trace.go:171","msg":"trace[1758798991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1589; }","duration":"217.14461ms","start":"2026-04-14T07:30:40.459747Z","end":"2026-04-14T07:30:40.676892Z","steps":["trace[1758798991] 'agreement among raft nodes before linearized reading' (duration: 217.073639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:30:40.676946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.964277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-14T07:30:40.677071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.636827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T07:30:40.677078Z","caller":"traceutil/trace.go:171","msg":"trace[1405278928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1589; }","duration":"292.1296ms","start":"2026-04-14T07:30:40.384932Z","end":"2026-04-14T07:30:40.677061Z","steps":["trace[1405278928] 'agreement among raft nodes before linearized reading' (duration: 291.884745ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:30:40.677093Z","caller":"traceutil/trace.go:171","msg":"trace[2030434411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1589; }","duration":"316.700768ms","start":"2026-04-14T07:30:40.360386Z","end":"2026-04-14T07:30:40.677087Z","steps":["trace[2030434411] 'agreement among raft nodes before linearized reading' (duration: 316.161035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:30:40.677079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.927191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-14T07:30:40.677127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:30:40.360365Z","time spent":"316.752399ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-14T07:30:40.677169Z","caller":"traceutil/trace.go:171","msg":"trace[182430013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1589; }","duration":"291.040944ms","start":"2026-04-14T07:30:40.386104Z","end":"2026-04-14T07:30:40.677145Z","steps":["trace[182430013] 'agreement among raft nodes before linearized reading' (duration: 290.676835ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:30:46.031513Z","caller":"traceutil/trace.go:171","msg":"trace[904942634] linearizableReadLoop","detail":"{readStateIndex:1683; appliedIndex:1682; }","duration":"306.897306ms","start":"2026-04-14T07:30:45.724594Z","end":"2026-04-14T07:30:46.031491Z","steps":["trace[904942634] 'read index received' (duration: 306.682901ms)","trace[904942634] 'applied index is now lower than readState.Index' (duration: 213.645µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:30:46.031653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.04412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T07:30:46.031679Z","caller":"traceutil/trace.go:171","msg":"trace[835120024] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1615; }","duration":"307.169893ms","start":"2026-04-14T07:30:45.724501Z","end":"2026-04-14T07:30:46.031671Z","steps":["trace[835120024] 'agreement among raft nodes before linearized reading' (duration: 307.077171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:30:46.031711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:30:45.724484Z","time spent":"307.220174ms","remote":"127.0.0.1:54624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-14T07:30:46.03186Z","caller":"traceutil/trace.go:171","msg":"trace[1324532470] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"345.342035ms","start":"2026-04-14T07:30:45.686277Z","end":"2026-04-14T07:30:46.031619Z","steps":["trace[1324532470] 'process raft request' (duration: 345.075828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:30:46.032158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:30:45.686261Z","time spent":"345.701683ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:30:48.862155Z","caller":"traceutil/trace.go:171","msg":"trace[2111173549] linearizableReadLoop","detail":"{readStateIndex:1693; appliedIndex:1692; }","duration":"477.906297ms","start":"2026-04-14T07:30:48.384205Z","end":"2026-04-14T07:30:48.862111Z","steps":["trace[2111173549] 'read index received' (duration: 477.715162ms)","trace[2111173549] 'applied index is now lower than readState.Index' (duration: 190.055µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:30:48.862217Z","caller":"traceutil/trace.go:171","msg":"trace[444372486] transaction","detail":"{read_only:false; response_revision:1625; number_of_response:1; }","duration":"567.296333ms","start":"2026-04-14T07:30:48.294906Z","end":"2026-04-14T07:30:48.862202Z","steps":["trace[444372486] 'process raft request' (duration: 567.046757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:30:48.862325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.104171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T07:30:48.862353Z","caller":"traceutil/trace.go:171","msg":"trace[1614054246] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1625; }","duration":"478.196024ms","start":"2026-04-14T07:30:48.384149Z","end":"2026-04-14T07:30:48.862345Z","steps":["trace[1614054246] 'agreement among raft nodes before linearized reading' (duration: 478.100171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:30:48.862379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:30:48.384126Z","time spent":"478.246315ms","remote":"127.0.0.1:42874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:30:48.86238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:30:48.294888Z","time spent":"567.421117ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:30:48.862454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.362368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-14T07:30:48.862495Z","caller":"traceutil/trace.go:171","msg":"trace[477223565] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1625; }","duration":"162.43968ms","start":"2026-04-14T07:30:48.700047Z","end":"2026-04-14T07:30:48.862486Z","steps":["trace[477223565] 'agreement among raft nodes before linearized reading' (duration: 162.302697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:30:48.862728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.704055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T07:30:48.862767Z","caller":"traceutil/trace.go:171","msg":"trace[1063453942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1625; }","duration":"119.784907ms","start":"2026-04-14T07:30:48.742973Z","end":"2026-04-14T07:30:48.862758Z","steps":["trace[1063453942] 'agreement among raft nodes before linearized reading' (duration: 119.692804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:30:48.862737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.311368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T07:30:48.862819Z","caller":"traceutil/trace.go:171","msg":"trace[2058151415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1625; }","duration":"139.421401ms","start":"2026-04-14T07:30:48.723392Z","end":"2026-04-14T07:30:48.862813Z","steps":["trace[2058151415] 'agreement among raft nodes before linearized reading' (duration: 139.283918ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:30:51.747267Z","caller":"traceutil/trace.go:171","msg":"trace[765021882] linearizableReadLoop","detail":"{readStateIndex:1712; appliedIndex:1711; }","duration":"105.030833ms","start":"2026-04-14T07:30:51.6422Z","end":"2026-04-14T07:30:51.747231Z","steps":["trace[765021882] 'read index received' (duration: 104.771927ms)","trace[765021882] 'applied index is now lower than readState.Index' (duration: 256.616µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:30:51.747466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.228268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T07:30:51.747522Z","caller":"traceutil/trace.go:171","msg":"trace[1766274346] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1643; }","duration":"105.361971ms","start":"2026-04-14T07:30:51.642144Z","end":"2026-04-14T07:30:51.747506Z","steps":["trace[1766274346] 'agreement among raft nodes before linearized reading' (duration: 105.234258ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:30:51.747609Z","caller":"traceutil/trace.go:171","msg":"trace[1629403340] transaction","detail":"{read_only:false; response_revision:1643; number_of_response:1; }","duration":"121.896469ms","start":"2026-04-14T07:30:51.625694Z","end":"2026-04-14T07:30:51.74759Z","steps":["trace[1629403340] 'process raft request' (duration: 121.277784ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:33:26.138263Z","caller":"traceutil/trace.go:171","msg":"trace[1982322676] linearizableReadLoop","detail":"{readStateIndex:2477; appliedIndex:2476; }","duration":"155.100406ms","start":"2026-04-14T07:33:25.983144Z","end":"2026-04-14T07:33:26.138245Z","steps":["trace[1982322676] 'read index received' (duration: 152.821449ms)","trace[1982322676] 'applied index is now lower than readState.Index' (duration: 2.278257ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:33:26.138531Z","caller":"traceutil/trace.go:171","msg":"trace[255795669] transaction","detail":"{read_only:false; response_revision:2376; number_of_response:1; }","duration":"188.464693ms","start":"2026-04-14T07:33:25.950035Z","end":"2026-04-14T07:33:26.1385Z","steps":["trace[255795669] 'process raft request' (duration: 185.981662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:33:26.138788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.620029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-14T07:33:26.138869Z","caller":"traceutil/trace.go:171","msg":"trace[701874529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2376; }","duration":"155.760973ms","start":"2026-04-14T07:33:25.98309Z","end":"2026-04-14T07:33:26.138851Z","steps":["trace[701874529] 'agreement among raft nodes before linearized reading' (duration: 155.420164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:33:26.139174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.067893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-14T07:33:26.139224Z","caller":"traceutil/trace.go:171","msg":"trace[829413801] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2376; }","duration":"115.155485ms","start":"2026-04-14T07:33:26.024057Z","end":"2026-04-14T07:33:26.139213Z","steps":["trace[829413801] 'agreement among raft nodes before linearized reading' (duration: 114.986691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:33:26.139495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.702631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T07:33:26.139554Z","caller":"traceutil/trace.go:171","msg":"trace[2109596581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2376; }","duration":"126.815024ms","start":"2026-04-14T07:33:26.01273Z","end":"2026-04-14T07:33:26.139545Z","steps":["trace[2109596581] 'agreement among raft nodes before linearized reading' (duration: 125.906572ms)"],"step_count":1}