{"level":"warn","ts":"2026-04-23T13:49:18.246098Z","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-23T13:49:18.246216Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-23T13:49:18.246293Z","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-23T13:49:18.246308Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-04-23T13:49:18.246331Z","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-23T13:49:18.24713Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-04-23T13:49:18.247227Z","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.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-23T13:49:18.395572Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"148.037917ms"} {"level":"info","ts":"2026-04-23T13:49:18.58942Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-04-23T13:49:18.589546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T13:49:18.589574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-04-23T13:49:18.5896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T13:49:18.589627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-04-23T13:49:18.589687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-04-23T13:49:18.59288Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T13:49:18.596033Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T13:49:18.618733Z","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-23T13:49:18.630737Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T13:49:18.631077Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T13:49:18.631217Z","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-23T13:49:18.631356Z","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-23T13:49:18.631372Z","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-23T13:49:18.63648Z","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-23T13:49:18.636604Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-04-23T13:49:18.636696Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-04-23T13:49:18.636985Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T13:49:18.637064Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T13:49:18.642925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-04-23T13:49:18.643116Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-04-23T13:49:19.490287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T13:49:19.490372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T13:49:19.490414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-04-23T13:49:19.490434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-04-23T13:49:19.490444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-04-23T13:49:19.49046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-04-23T13:49:19.490472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-04-23T13:49:19.519928Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T13:49:19.519955Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T13:49:19.519935Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T13:49:19.520064Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T13:49:19.520236Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T13:49:19.520258Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T13:49:19.523285Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-04-23T13:49:19.5233Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T13:49:19.527586Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T13:49:19.527861Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T13:49:19.527956Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T13:49:24.842153Z","caller":"traceutil/trace.go:171","msg":"trace[1313707887] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"109.033236ms","start":"2026-04-23T13:49:24.733097Z","end":"2026-04-23T13:49:24.84213Z","steps":["trace[1313707887] 'process raft request' (duration: 88.718855ms)","trace[1313707887] 'compare' (duration: 20.119958ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:49:24.842372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.013553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:49:24.8425Z","caller":"traceutil/trace.go:171","msg":"trace[474510198] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:283; }","duration":"108.211976ms","start":"2026-04-23T13:49:24.734253Z","end":"2026-04-23T13:49:24.842465Z","steps":["trace[474510198] 'agreement among raft nodes before linearized reading' (duration: 107.922982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:25.755512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.93459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:49:25.755623Z","caller":"traceutil/trace.go:171","msg":"trace[711383389] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"838.246966ms","start":"2026-04-23T13:49:24.917358Z","end":"2026-04-23T13:49:25.755605Z","steps":["trace[711383389] 'process raft request' (duration: 356.207431ms)","trace[711383389] 'compare' (duration: 480.675917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:49:25.755677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:24.917341Z","time spent":"838.313667ms","remote":"127.0.0.1:41976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1934,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T13:49:25.821407Z","caller":"traceutil/trace.go:171","msg":"trace[826852372] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:300; }","duration":"889.029385ms","start":"2026-04-23T13:49:24.932354Z","end":"2026-04-23T13:49:25.821383Z","steps":["trace[826852372] 'read index received' (duration: 341.224067ms)","trace[826852372] 'applied index is now lower than readState.Index' (duration: 547.804478ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:49:25.821517Z","caller":"traceutil/trace.go:171","msg":"trace[612090943] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"897.261576ms","start":"2026-04-23T13:49:24.924246Z","end":"2026-04-23T13:49:25.821508Z","steps":["trace[612090943] 'process raft request' (duration: 897.076944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:25.821638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.268618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T13:49:25.821713Z","caller":"traceutil/trace.go:171","msg":"trace[1771649273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:296; }","duration":"889.386858ms","start":"2026-04-23T13:49:24.93231Z","end":"2026-04-23T13:49:25.821697Z","steps":["trace[1771649273] 'agreement among raft nodes before linearized reading' (duration: 889.227937ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:49:25.821641Z","caller":"traceutil/trace.go:171","msg":"trace[1229226919] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"903.98011ms","start":"2026-04-23T13:49:24.917615Z","end":"2026-04-23T13:49:25.821595Z","steps":["trace[1229226919] 'process raft request' (duration: 903.534513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:25.821819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:24.924227Z","time spent":"897.324857ms","remote":"127.0.0.1:42046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4183,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:49:25.822028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:24.917605Z","time spent":"904.324324ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T13:49:25.821827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:24.932294Z","time spent":"889.522841ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T13:49:25.950863Z","caller":"traceutil/trace.go:171","msg":"trace[1550882565] transaction","detail":"{read_only:false; number_of_response:0; response_revision:296; }","duration":"230.776257ms","start":"2026-04-23T13:49:25.720061Z","end":"2026-04-23T13:49:25.950837Z","steps":["trace[1550882565] 'process raft request' (duration: 230.680885ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:49:25.950967Z","caller":"traceutil/trace.go:171","msg":"trace[1952022344] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:303; }","duration":"129.4692ms","start":"2026-04-23T13:49:25.821475Z","end":"2026-04-23T13:49:25.950944Z","steps":["trace[1952022344] 'read index received' (duration: 129.198786ms)","trace[1952022344] 'applied index is now lower than readState.Index' (duration: 269.554µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:49:25.950939Z","caller":"traceutil/trace.go:171","msg":"trace[328985914] transaction","detail":"{read_only:false; number_of_response:0; response_revision:296; }","duration":"230.757417ms","start":"2026-04-23T13:49:25.720177Z","end":"2026-04-23T13:49:25.950934Z","steps":["trace[328985914] 'process raft request' (duration: 230.587154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:25.951201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016602109s","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-23T13:49:25.951243Z","caller":"traceutil/trace.go:171","msg":"trace[202015096] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:297; }","duration":"1.016720781s","start":"2026-04-23T13:49:24.934511Z","end":"2026-04-23T13:49:25.951232Z","steps":["trace[202015096] 'agreement among raft nodes before linearized reading' (duration: 1.016555239s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:25.951314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:24.934495Z","time spent":"1.016808212s","remote":"127.0.0.1:41986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-23T13:49:25.950884Z","caller":"traceutil/trace.go:171","msg":"trace[1678185722] transaction","detail":"{read_only:false; number_of_response:0; response_revision:296; }","duration":"231.345774ms","start":"2026-04-23T13:49:25.719513Z","end":"2026-04-23T13:49:25.950858Z","steps":["trace[1678185722] 'process raft request' (duration: 231.144811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:25.951677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.159384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:49:25.951741Z","caller":"traceutil/trace.go:171","msg":"trace[6397653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:297; }","duration":"851.256426ms","start":"2026-04-23T13:49:25.100474Z","end":"2026-04-23T13:49:25.95173Z","steps":["trace[6397653] 'agreement among raft nodes before linearized reading' (duration: 851.172774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:25.951773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:25.100456Z","time spent":"851.308316ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T13:49:25.951978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014409904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T13:49:25.952095Z","caller":"traceutil/trace.go:171","msg":"trace[809972328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:297; }","duration":"1.014558875s","start":"2026-04-23T13:49:24.937516Z","end":"2026-04-23T13:49:25.952075Z","steps":["trace[809972328] 'agreement among raft nodes before linearized reading' (duration: 1.014388693s)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:49:25.952099Z","caller":"traceutil/trace.go:171","msg":"trace[1322270352] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"124.615647ms","start":"2026-04-23T13:49:25.827414Z","end":"2026-04-23T13:49:25.952029Z","steps":["trace[1322270352] 'process raft request' (duration: 124.485894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:25.952288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:24.937505Z","time spent":"1.014669886s","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T13:49:25.952352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.652894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-04-23T13:49:25.952412Z","caller":"traceutil/trace.go:171","msg":"trace[459930795] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:297; }","duration":"233.742954ms","start":"2026-04-23T13:49:25.718656Z","end":"2026-04-23T13:49:25.952399Z","steps":["trace[459930795] 'agreement among raft nodes before linearized reading' (duration: 232.963114ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:49:25.952522Z","caller":"traceutil/trace.go:171","msg":"trace[430201411] transaction","detail":"{read_only:false; number_of_response:0; response_revision:296; }","duration":"230.364382ms","start":"2026-04-23T13:49:25.722149Z","end":"2026-04-23T13:49:25.952513Z","steps":["trace[430201411] 'process raft request' (duration: 228.634689ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:49:25.952539Z","caller":"traceutil/trace.go:171","msg":"trace[1154806918] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"189.554089ms","start":"2026-04-23T13:49:25.762972Z","end":"2026-04-23T13:49:25.952526Z","steps":["trace[1154806918] 'process raft request' (duration: 187.832876ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:49:27.250979Z","caller":"traceutil/trace.go:171","msg":"trace[1877749715] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"216.134107ms","start":"2026-04-23T13:49:27.034826Z","end":"2026-04-23T13:49:27.250961Z","steps":["trace[1877749715] 'process raft request' (duration: 216.033775ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:49:32.162459Z","caller":"traceutil/trace.go:171","msg":"trace[339037538] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"163.200572ms","start":"2026-04-23T13:49:31.999236Z","end":"2026-04-23T13:49:32.162437Z","steps":["trace[339037538] 'process raft request' (duration: 163.07795ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:49:32.166962Z","caller":"traceutil/trace.go:171","msg":"trace[598975980] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"167.469741ms","start":"2026-04-23T13:49:31.99947Z","end":"2026-04-23T13:49:32.16694Z","steps":["trace[598975980] 'process raft request' (duration: 167.373999ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:49:34.819221Z","caller":"traceutil/trace.go:171","msg":"trace[102825833] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"216.828096ms","start":"2026-04-23T13:49:34.602374Z","end":"2026-04-23T13:49:34.819202Z","steps":["trace[102825833] 'process raft request' (duration: 216.715194ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:49:36.283242Z","caller":"traceutil/trace.go:171","msg":"trace[858863295] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"209.496889ms","start":"2026-04-23T13:49:36.073723Z","end":"2026-04-23T13:49:36.28322Z","steps":["trace[858863295] 'process raft request' (duration: 184.827857ms)","trace[858863295] 'compare' (duration: 24.424288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:49:36.600875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.223923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:49:36.600963Z","caller":"traceutil/trace.go:171","msg":"trace[1814253504] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"261.531921ms","start":"2026-04-23T13:49:36.33942Z","end":"2026-04-23T13:49:36.600951Z","steps":["trace[1814253504] 'process raft request' (duration: 156.142825ms)","trace[1814253504] 'compare' (duration: 105.109801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:49:50.966832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.474352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:49:50.966917Z","caller":"traceutil/trace.go:171","msg":"trace[749928198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"226.604525ms","start":"2026-04-23T13:49:50.740294Z","end":"2026-04-23T13:49:50.966899Z","steps":["trace[749928198] 'range keys from in-memory index tree' (duration: 226.351979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:50.967637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.321489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:49:50.967729Z","caller":"traceutil/trace.go:171","msg":"trace[386288301] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"210.554634ms","start":"2026-04-23T13:49:50.757159Z","end":"2026-04-23T13:49:50.967713Z","steps":["trace[386288301] 'process raft request' (duration: 81.827906ms)","trace[386288301] 'compare' (duration: 128.121554ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:49:52.632709Z","caller":"traceutil/trace.go:171","msg":"trace[862414217] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"355.188869ms","start":"2026-04-23T13:49:52.277495Z","end":"2026-04-23T13:49:52.632684Z","steps":["trace[862414217] 'process raft request' (duration: 333.398957ms)","trace[862414217] 'compare' (duration: 21.363953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:49:52.632838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:52.277473Z","time spent":"355.297763ms","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:49:52.633262Z","caller":"traceutil/trace.go:171","msg":"trace[1064340560] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"341.612087ms","start":"2026-04-23T13:49:52.291606Z","end":"2026-04-23T13:49:52.633218Z","steps":["trace[1064340560] 'process raft request' (duration: 341.161827ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:49:52.63344Z","caller":"traceutil/trace.go:171","msg":"trace[1471351443] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:582; }","duration":"233.200687ms","start":"2026-04-23T13:49:52.400228Z","end":"2026-04-23T13:49:52.633428Z","steps":["trace[1471351443] 'read index received' (duration: 210.746909ms)","trace[1471351443] 'applied index is now lower than readState.Index' (duration: 22.453248ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:49:52.633509Z","caller":"traceutil/trace.go:171","msg":"trace[1128749548] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"306.511988ms","start":"2026-04-23T13:49:52.326988Z","end":"2026-04-23T13:49:52.6335Z","steps":["trace[1128749548] 'process raft request' (duration: 305.925804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:52.63356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:52.326964Z","time spent":"306.564979ms","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:49:52.633603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.375911ms","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-23T13:49:52.63365Z","caller":"traceutil/trace.go:171","msg":"trace[1747707182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"233.456763ms","start":"2026-04-23T13:49:52.400182Z","end":"2026-04-23T13:49:52.633639Z","steps":["trace[1747707182] 'agreement among raft nodes before linearized reading' (duration: 233.34346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:52.633633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.366014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-23T13:49:52.633705Z","caller":"traceutil/trace.go:171","msg":"trace[392646590] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:565; }","duration":"195.487787ms","start":"2026-04-23T13:49:52.438199Z","end":"2026-04-23T13:49:52.633687Z","steps":["trace[392646590] 'agreement among raft nodes before linearized reading' (duration: 195.312744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:52.633556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:52.291584Z","time spent":"341.831791ms","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T13:49:54.24289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868928685703650,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T13:49:54.743761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868928685703650,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T13:49:55.244933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868928685703650,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T13:49:55.505459Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.108618462s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-23T13:49:55.742042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999901023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-23T13:49:55.742229Z","caller":"traceutil/trace.go:171","msg":"trace[520499437] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000135379s","start":"2026-04-23T13:49:53.742072Z","end":"2026-04-23T13:49:55.742207Z","steps":["trace[520499437] 'agreement among raft nodes before linearized reading' (duration: 1.999922864s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:55.742314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:53.742056Z","time spent":"2.0002424s","remote":"127.0.0.1:41844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T13:49:57.557302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.167055436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-04-23T13:49:57.55739Z","caller":"traceutil/trace.go:171","msg":"trace[1675202400] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:604; }","duration":"4.167162088s","start":"2026-04-23T13:49:53.390207Z","end":"2026-04-23T13:49:57.557369Z","steps":["trace[1675202400] 'range keys from in-memory index tree' (duration: 4.166914872s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.557431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:53.390198Z","time spent":"4.16722315s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10892,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-23T13:49:57.557417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.542941197s","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-23T13:49:57.557575Z","caller":"traceutil/trace.go:171","msg":"trace[166227850] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:604; }","duration":"1.543122862s","start":"2026-04-23T13:49:56.014428Z","end":"2026-04-23T13:49:57.557551Z","steps":["trace[166227850] 'range keys from in-memory index tree' (duration: 1.542926147s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.558002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.052095326s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:49:57.558081Z","caller":"traceutil/trace.go:171","msg":"trace[1275836317] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"4.161476177s","start":"2026-04-23T13:49:53.396589Z","end":"2026-04-23T13:49:57.558065Z","steps":["trace[1275836317] 'process raft request' (duration: 2.109252278s)","trace[1275836317] 'compare' (duration: 2.050977108s)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:49:57.558134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:53.396576Z","time spent":"4.161530797s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:49:57.635056Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.127910356s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T13:49:57.635802Z","caller":"traceutil/trace.go:171","msg":"trace[1875711143] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:625; }","duration":"3.89368118s","start":"2026-04-23T13:49:53.742102Z","end":"2026-04-23T13:49:57.635783Z","steps":["trace[1875711143] 'read index received' (duration: 1.763794437s)","trace[1875711143] 'applied index is now lower than readState.Index' (duration: 2.129885983s)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:49:57.63592Z","caller":"traceutil/trace.go:171","msg":"trace[1659197099] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"4.150882989s","start":"2026-04-23T13:49:53.48503Z","end":"2026-04-23T13:49:57.635913Z","steps":["trace[1659197099] 'process raft request' (duration: 4.150325966s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.636071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:53.485Z","time spent":"4.151003634s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:49:57.636164Z","caller":"traceutil/trace.go:171","msg":"trace[1207676347] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"3.664985078s","start":"2026-04-23T13:49:53.971115Z","end":"2026-04-23T13:49:57.6361Z","steps":["trace[1207676347] 'process raft request' (duration: 3.66464364s)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:49:57.636202Z","caller":"traceutil/trace.go:171","msg":"trace[233427549] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"4.066396367s","start":"2026-04-23T13:49:53.569673Z","end":"2026-04-23T13:49:57.636069Z","steps":["trace[233427549] 'process raft request' (duration: 4.066041628s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.636348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:53.971082Z","time spent":"3.665180362s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T13:49:57.636539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:53.569648Z","time spent":"4.066720644s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T13:49:57.650132Z","caller":"traceutil/trace.go:171","msg":"trace[1249677536] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.001107339s","start":"2026-04-23T13:49:56.649003Z","end":"2026-04-23T13:49:57.650111Z","steps":["trace[1249677536] 'process raft request' (duration: 1.000973745s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.650242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:56.648979Z","time spent":"1.001203062s","remote":"127.0.0.1:42046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4559,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:49:57.650297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.881677587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:49:57.650346Z","caller":"traceutil/trace.go:171","msg":"trace[946171932] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:611; }","duration":"3.88177878s","start":"2026-04-23T13:49:53.768553Z","end":"2026-04-23T13:49:57.650332Z","steps":["trace[946171932] 'agreement among raft nodes before linearized reading' (duration: 3.881678197s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.650374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:53.76853Z","time spent":"3.88183859s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-04-23T13:49:57.650545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.282329609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:49:57.650573Z","caller":"traceutil/trace.go:171","msg":"trace[1448360597] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:611; }","duration":"3.28239565s","start":"2026-04-23T13:49:54.36817Z","end":"2026-04-23T13:49:57.650566Z","steps":["trace[1448360597] 'agreement among raft nodes before linearized reading' (duration: 3.282358709s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.650574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.676519755s","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-23T13:49:57.650591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:54.368153Z","time spent":"3.282433211s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-23T13:49:57.650603Z","caller":"traceutil/trace.go:171","msg":"trace[19662639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:611; }","duration":"2.676571877s","start":"2026-04-23T13:49:54.974021Z","end":"2026-04-23T13:49:57.650593Z","steps":["trace[19662639] 'agreement among raft nodes before linearized reading' (duration: 2.676474714s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.650599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.082143912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T13:49:57.650636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:54.974011Z","time spent":"2.676619577s","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T13:49:57.650664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.482386412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:49:57.650674Z","caller":"traceutil/trace.go:171","msg":"trace[246880984] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:611; }","duration":"3.082264396s","start":"2026-04-23T13:49:54.568388Z","end":"2026-04-23T13:49:57.650652Z","steps":["trace[246880984] 'agreement among raft nodes before linearized reading' (duration: 3.082119111s)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:49:57.650691Z","caller":"traceutil/trace.go:171","msg":"trace[657321241] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:611; }","duration":"3.482428832s","start":"2026-04-23T13:49:54.168254Z","end":"2026-04-23T13:49:57.650683Z","steps":["trace[657321241] 'agreement among raft nodes before linearized reading' (duration: 3.482396282s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.650712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:54.168244Z","time spent":"3.482464412s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-04-23T13:49:57.650637Z","caller":"traceutil/trace.go:171","msg":"trace[378132906] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"2.018754519s","start":"2026-04-23T13:49:55.631822Z","end":"2026-04-23T13:49:57.650577Z","steps":["trace[378132906] 'process raft request' (duration: 2.018020621s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.650849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.081617015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4074"} {"level":"warn","ts":"2026-04-23T13:49:57.650872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.28216008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5168"} {"level":"warn","ts":"2026-04-23T13:49:57.650869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.682091337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-23T13:49:57.650902Z","caller":"traceutil/trace.go:171","msg":"trace[435990415] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:611; }","duration":"2.2822187s","start":"2026-04-23T13:49:55.368675Z","end":"2026-04-23T13:49:57.650893Z","steps":["trace[435990415] 'agreement among raft nodes before linearized reading' (duration: 2.282150898s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.65093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:55.368657Z","time spent":"2.282267622s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5190,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-04-23T13:49:57.650927Z","caller":"traceutil/trace.go:171","msg":"trace[27555216] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:611; }","duration":"2.682178199s","start":"2026-04-23T13:49:54.968732Z","end":"2026-04-23T13:49:57.65091Z","steps":["trace[27555216] 'agreement among raft nodes before linearized reading' (duration: 2.682008795s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.650966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:54.968714Z","time spent":"2.682241671s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-04-23T13:49:57.650878Z","caller":"traceutil/trace.go:171","msg":"trace[1052728889] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:611; }","duration":"2.08176527s","start":"2026-04-23T13:49:55.569104Z","end":"2026-04-23T13:49:57.650869Z","steps":["trace[1052728889] 'agreement among raft nodes before linearized reading' (duration: 2.081704808s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.651069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:55.569076Z","time spent":"2.081987044s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4096,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-04-23T13:49:57.650717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:54.56837Z","time spent":"3.082338626s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-23T13:49:57.65146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:55.631794Z","time spent":"2.019522339s","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:49:57.658928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.50021093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-23T13:49:57.659067Z","caller":"traceutil/trace.go:171","msg":"trace[1883045503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"3.500414964s","start":"2026-04-23T13:49:54.15862Z","end":"2026-04-23T13:49:57.659035Z","steps":["trace[1883045503] 'agreement among raft nodes before linearized reading' (duration: 3.492580323s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.659106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:54.158599Z","time spent":"3.500498537s","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T13:49:57.659425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.490236197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-23T13:49:57.659493Z","caller":"traceutil/trace.go:171","msg":"trace[1761269499] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:611; }","duration":"2.490335111s","start":"2026-04-23T13:49:55.169147Z","end":"2026-04-23T13:49:57.659482Z","steps":["trace[1761269499] 'agreement among raft nodes before linearized reading' (duration: 2.481834243s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.659606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:55.16913Z","time spent":"2.490445053s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-04-23T13:49:57.659801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.669572009s","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-23T13:49:57.659826Z","caller":"traceutil/trace.go:171","msg":"trace[35505578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"2.6696185s","start":"2026-04-23T13:49:54.990201Z","end":"2026-04-23T13:49:57.659819Z","steps":["trace[35505578] 'agreement among raft nodes before linearized reading' (duration: 2.660787184s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.659865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:54.990191Z","time spent":"2.669667981s","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T13:49:57.659912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.915843279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:49:57.659992Z","caller":"traceutil/trace.go:171","msg":"trace[1934633040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"1.916721882s","start":"2026-04-23T13:49:55.743256Z","end":"2026-04-23T13:49:57.659978Z","steps":["trace[1934633040] 'agreement among raft nodes before linearized reading' (duration: 1.907960547s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.660027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:55.74324Z","time spent":"1.916777033s","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T13:49:57.660057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.899674294s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T13:49:57.660084Z","caller":"traceutil/trace.go:171","msg":"trace[699593589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"2.899733364s","start":"2026-04-23T13:49:54.760344Z","end":"2026-04-23T13:49:57.660077Z","steps":["trace[699593589] 'agreement among raft nodes before linearized reading' (duration: 2.890682522s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.660103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:54.760324Z","time spent":"2.899774726s","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T13:49:57.660216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095394197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:49:57.660244Z","caller":"traceutil/trace.go:171","msg":"trace[1468612588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"1.095482231s","start":"2026-04-23T13:49:56.564754Z","end":"2026-04-23T13:49:57.660237Z","steps":["trace[1468612588] 'agreement among raft nodes before linearized reading' (duration: 1.086455739s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.660271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:56.564723Z","time spent":"1.095536412s","remote":"127.0.0.1:41844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T13:49:57.6602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.015632836s","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-23T13:49:57.660434Z","caller":"traceutil/trace.go:171","msg":"trace[1541275879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"3.016092418s","start":"2026-04-23T13:49:54.644334Z","end":"2026-04-23T13:49:57.660426Z","steps":["trace[1541275879] 'agreement among raft nodes before linearized reading' (duration: 3.006871422s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.660466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:54.644305Z","time spent":"3.01615206s","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T13:49:57.660289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.892530204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-23T13:49:57.660544Z","caller":"traceutil/trace.go:171","msg":"trace[568608733] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:611; }","duration":"2.892803581s","start":"2026-04-23T13:49:54.767733Z","end":"2026-04-23T13:49:57.660536Z","steps":["trace[568608733] 'agreement among raft nodes before linearized reading' (duration: 2.883434471s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.660565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:54.767722Z","time spent":"2.892837043s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-04-23T13:49:57.660739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.868806398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-tgpfc\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-04-23T13:49:57.66078Z","caller":"traceutil/trace.go:171","msg":"trace[445258515] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-tgpfc; range_end:; response_count:1; response_revision:611; }","duration":"3.86887326s","start":"2026-04-23T13:49:53.791894Z","end":"2026-04-23T13:49:57.660767Z","steps":["trace[445258515] 'agreement among raft nodes before linearized reading' (duration: 3.859334416s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:49:57.660809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:49:53.791873Z","time spent":"3.868929901s","remote":"127.0.0.1:42048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4705,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-tgpfc\" "} {"level":"info","ts":"2026-04-23T13:50:02.89204Z","caller":"traceutil/trace.go:171","msg":"trace[1596425030] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"196.885685ms","start":"2026-04-23T13:50:02.695126Z","end":"2026-04-23T13:50:02.892012Z","steps":["trace[1596425030] 'process raft request' (duration: 195.978272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:02.892223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.348219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:50:02.892317Z","caller":"traceutil/trace.go:171","msg":"trace[1988411155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:669; }","duration":"153.483951ms","start":"2026-04-23T13:50:02.738811Z","end":"2026-04-23T13:50:02.892295Z","steps":["trace[1988411155] 'agreement among raft nodes before linearized reading' (duration: 153.319097ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:50:02.891951Z","caller":"traceutil/trace.go:171","msg":"trace[1218242383] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"153.085391ms","start":"2026-04-23T13:50:02.738844Z","end":"2026-04-23T13:50:02.89193Z","steps":["trace[1218242383] 'read index received' (duration: 152.021004ms)","trace[1218242383] 'applied index is now lower than readState.Index' (duration: 1.063757ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:50:03.808587Z","caller":"traceutil/trace.go:171","msg":"trace[1565796546] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"103.797893ms","start":"2026-04-23T13:50:03.704769Z","end":"2026-04-23T13:50:03.808567Z","steps":["trace[1565796546] 'process raft request' (duration: 103.513755ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:50:03.827065Z","caller":"traceutil/trace.go:171","msg":"trace[567284924] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:704; }","duration":"114.239005ms","start":"2026-04-23T13:50:03.712801Z","end":"2026-04-23T13:50:03.82704Z","steps":["trace[567284924] 'read index received' (duration: 96.117962ms)","trace[567284924] 'applied index is now lower than readState.Index' (duration: 18.119643ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:50:03.827148Z","caller":"traceutil/trace.go:171","msg":"trace[1172487308] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"122.02871ms","start":"2026-04-23T13:50:03.705096Z","end":"2026-04-23T13:50:03.827124Z","steps":["trace[1172487308] 'process raft request' (duration: 121.896336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:03.827358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.324257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-23T13:50:03.827368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.547923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T13:50:03.827453Z","caller":"traceutil/trace.go:171","msg":"trace[746382116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"114.662317ms","start":"2026-04-23T13:50:03.712771Z","end":"2026-04-23T13:50:03.827433Z","steps":["trace[746382116] 'agreement among raft nodes before linearized reading' (duration: 114.416411ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:50:03.827401Z","caller":"traceutil/trace.go:171","msg":"trace[457040332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"114.409031ms","start":"2026-04-23T13:50:03.712977Z","end":"2026-04-23T13:50:03.827386Z","steps":["trace[457040332] 'agreement among raft nodes before linearized reading' (duration: 114.246416ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:50:03.827529Z","caller":"traceutil/trace.go:171","msg":"trace[1609440636] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"122.41719ms","start":"2026-04-23T13:50:03.705097Z","end":"2026-04-23T13:50:03.827514Z","steps":["trace[1609440636] 'process raft request' (duration: 121.740683ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:50:04.40176Z","caller":"traceutil/trace.go:171","msg":"trace[1036856570] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"569.321911ms","start":"2026-04-23T13:50:03.832414Z","end":"2026-04-23T13:50:04.401736Z","steps":["trace[1036856570] 'process raft request' (duration: 569.139275ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:50:04.401865Z","caller":"traceutil/trace.go:171","msg":"trace[2101475146] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"559.294348ms","start":"2026-04-23T13:50:03.84255Z","end":"2026-04-23T13:50:04.401845Z","steps":["trace[2101475146] 'process raft request' (duration: 559.136814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:04.401964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:03.842525Z","time spent":"559.388411ms","remote":"127.0.0.1:42048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:50:04.401939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:03.832386Z","time spent":"569.439794ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:50:06.822212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.082057801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:50:06.822466Z","caller":"traceutil/trace.go:171","msg":"trace[828114133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"2.082319758s","start":"2026-04-23T13:50:04.740128Z","end":"2026-04-23T13:50:06.822448Z","steps":["trace[828114133] 'range keys from in-memory index tree' (duration: 2.081957968s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:06.822503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:04.740075Z","time spent":"2.082419611s","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T13:50:06.822514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008554728s","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-23T13:50:06.822587Z","caller":"traceutil/trace.go:171","msg":"trace[1558850411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:686; }","duration":"1.008669162s","start":"2026-04-23T13:50:05.813901Z","end":"2026-04-23T13:50:06.822571Z","steps":["trace[1558850411] 'range keys from in-memory index tree' (duration: 1.008397155s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:06.822628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:05.813882Z","time spent":"1.008738104s","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T13:50:06.822798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.423543ms","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-23T13:50:06.822832Z","caller":"traceutil/trace.go:171","msg":"trace[557619924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:686; }","duration":"991.498395ms","start":"2026-04-23T13:50:05.831324Z","end":"2026-04-23T13:50:06.822823Z","steps":["trace[557619924] 'range keys from in-memory index tree' (duration: 991.284678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:06.822861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:05.831297Z","time spent":"991.555316ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T13:50:06.82304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.483574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:50:06.823125Z","caller":"traceutil/trace.go:171","msg":"trace[1187322162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"260.599455ms","start":"2026-04-23T13:50:06.562508Z","end":"2026-04-23T13:50:06.823107Z","steps":["trace[1187322162] 'range keys from in-memory index tree' (duration: 260.388429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:06.823188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.857087ms","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-23T13:50:06.823269Z","caller":"traceutil/trace.go:171","msg":"trace[540580816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:686; }","duration":"991.048993ms","start":"2026-04-23T13:50:05.832205Z","end":"2026-04-23T13:50:06.823254Z","steps":["trace[540580816] 'range keys from in-memory index tree' (duration: 990.703455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:06.823335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:05.83219Z","time spent":"991.133045ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T13:50:06.823461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.383332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T13:50:06.823504Z","caller":"traceutil/trace.go:171","msg":"trace[990679379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:686; }","duration":"964.456064ms","start":"2026-04-23T13:50:05.859038Z","end":"2026-04-23T13:50:06.823494Z","steps":["trace[990679379] 'range keys from in-memory index tree' (duration: 964.241569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:06.823531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:05.859022Z","time spent":"964.502356ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T13:50:06.823776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.70396ms","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-23T13:50:06.823824Z","caller":"traceutil/trace.go:171","msg":"trace[1156063943] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:686; }","duration":"809.754441ms","start":"2026-04-23T13:50:06.014064Z","end":"2026-04-23T13:50:06.823818Z","steps":["trace[1156063943] 'range keys from in-memory index tree' (duration: 809.69668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:06.823834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.214507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T13:50:06.82387Z","caller":"traceutil/trace.go:171","msg":"trace[497695575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:686; }","duration":"417.286069ms","start":"2026-04-23T13:50:06.406574Z","end":"2026-04-23T13:50:06.82386Z","steps":["trace[497695575] 'range keys from in-memory index tree' (duration: 417.121645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:06.823908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:06.406554Z","time spent":"417.34757ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T13:50:07.030023Z","caller":"traceutil/trace.go:171","msg":"trace[826342609] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"204.502995ms","start":"2026-04-23T13:50:06.825499Z","end":"2026-04-23T13:50:07.030002Z","steps":["trace[826342609] 'process raft request' (duration: 204.374651ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:50:07.355907Z","caller":"traceutil/trace.go:171","msg":"trace[864064169] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"527.54186ms","start":"2026-04-23T13:50:06.828342Z","end":"2026-04-23T13:50:07.355883Z","steps":["trace[864064169] 'process raft request' (duration: 527.476978ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:50:07.355936Z","caller":"traceutil/trace.go:171","msg":"trace[1560561343] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"527.931039ms","start":"2026-04-23T13:50:06.827965Z","end":"2026-04-23T13:50:07.355896Z","steps":["trace[1560561343] 'process raft request' (duration: 527.682342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:07.356049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:06.828336Z","time spent":"527.639682ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:50:07.356099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:06.827952Z","time spent":"528.068584ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:50:07.356199Z","caller":"traceutil/trace.go:171","msg":"trace[1106346577] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"527.879327ms","start":"2026-04-23T13:50:06.828295Z","end":"2026-04-23T13:50:07.356174Z","steps":["trace[1106346577] 'process raft request' (duration: 527.487187ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:50:07.35633Z","caller":"traceutil/trace.go:171","msg":"trace[568639497] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"527.55139ms","start":"2026-04-23T13:50:06.828766Z","end":"2026-04-23T13:50:07.356317Z","steps":["trace[568639497] 'process raft request' (duration: 527.084487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:07.356338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:06.828289Z","time spent":"527.98238ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:50:07.356437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:06.828759Z","time spent":"527.606291ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:50:07.356588Z","caller":"traceutil/trace.go:171","msg":"trace[1370015874] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"527.191019ms","start":"2026-04-23T13:50:06.829382Z","end":"2026-04-23T13:50:07.356573Z","steps":["trace[1370015874] 'process raft request' (duration: 526.626724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:07.35674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:06.829376Z","time spent":"527.264462ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:50:23.891052Z","caller":"traceutil/trace.go:171","msg":"trace[1666490760] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"458.452087ms","start":"2026-04-23T13:50:23.432551Z","end":"2026-04-23T13:50:23.891003Z","steps":["trace[1666490760] 'read index received' (duration: 458.19296ms)","trace[1666490760] 'applied index is now lower than readState.Index' (duration: 258.157µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:50:23.891099Z","caller":"traceutil/trace.go:171","msg":"trace[2144584555] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"965.124292ms","start":"2026-04-23T13:50:22.925939Z","end":"2026-04-23T13:50:23.891063Z","steps":["trace[2144584555] 'process raft request' (duration: 964.892455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:23.891375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.24208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T13:50:23.89139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.845376ms","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-23T13:50:23.891419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.66704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:50:23.891461Z","caller":"traceutil/trace.go:171","msg":"trace[761728634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"453.949349ms","start":"2026-04-23T13:50:23.437498Z","end":"2026-04-23T13:50:23.891447Z","steps":["trace[761728634] 'agreement among raft nodes before linearized reading' (duration: 453.780464ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:50:23.891464Z","caller":"traceutil/trace.go:171","msg":"trace[29631478] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:802; }","duration":"213.353573ms","start":"2026-04-23T13:50:23.678079Z","end":"2026-04-23T13:50:23.891433Z","steps":["trace[29631478] 'agreement among raft nodes before linearized reading' (duration: 213.24189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:23.891511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:23.437489Z","time spent":"454.013371ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T13:50:23.891511Z","caller":"traceutil/trace.go:171","msg":"trace[315637608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"150.794074ms","start":"2026-04-23T13:50:23.740698Z","end":"2026-04-23T13:50:23.891492Z","steps":["trace[315637608] 'agreement among raft nodes before linearized reading' (duration: 150.617038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:23.891847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.959773ms","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-23T13:50:23.891912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.839599ms","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-23T13:50:23.891948Z","caller":"traceutil/trace.go:171","msg":"trace[246044563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"458.91285ms","start":"2026-04-23T13:50:23.433024Z","end":"2026-04-23T13:50:23.891937Z","steps":["trace[246044563] 'agreement among raft nodes before linearized reading' (duration: 458.821827ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:50:23.891957Z","caller":"traceutil/trace.go:171","msg":"trace[520811683] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"458.070416ms","start":"2026-04-23T13:50:23.43384Z","end":"2026-04-23T13:50:23.891911Z","steps":["trace[520811683] 'agreement among raft nodes before linearized reading' (duration: 457.765086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:23.891985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:23.433008Z","time spent":"458.970763ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T13:50:23.8921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:23.433831Z","time spent":"458.170617ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T13:50:23.892224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.608851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T13:50:23.892502Z","caller":"traceutil/trace.go:171","msg":"trace[1928897677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"459.910482ms","start":"2026-04-23T13:50:23.432582Z","end":"2026-04-23T13:50:23.892492Z","steps":["trace[1928897677] 'agreement among raft nodes before linearized reading' (duration: 458.655403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:23.892542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:23.432566Z","time spent":"459.967123ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T13:50:23.891364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:22.925921Z","time spent":"965.256865ms","remote":"127.0.0.1:42048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:50:23.893094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.703514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T13:50:23.89431Z","caller":"traceutil/trace.go:171","msg":"trace[152499913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"461.781608ms","start":"2026-04-23T13:50:23.432509Z","end":"2026-04-23T13:50:23.894291Z","steps":["trace[152499913] 'agreement among raft nodes before linearized reading' (duration: 458.644702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:23.894631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:23.432492Z","time spent":"461.993554ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T13:50:27.066063Z","caller":"traceutil/trace.go:171","msg":"trace[30419694] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"117.387149ms","start":"2026-04-23T13:50:26.948654Z","end":"2026-04-23T13:50:27.066041Z","steps":["trace[30419694] 'process raft request' (duration: 117.242115ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:50:27.209881Z","caller":"traceutil/trace.go:171","msg":"trace[1425090004] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"136.591279ms","start":"2026-04-23T13:50:27.073271Z","end":"2026-04-23T13:50:27.209862Z","steps":["trace[1425090004] 'process raft request' (duration: 104.240318ms)","trace[1425090004] 'compare' (duration: 32.276619ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:50:27.47952Z","caller":"traceutil/trace.go:171","msg":"trace[437311712] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"263.812455ms","start":"2026-04-23T13:50:27.215682Z","end":"2026-04-23T13:50:27.479495Z","steps":["trace[437311712] 'process raft request' (duration: 164.307205ms)","trace[437311712] 'compare' (duration: 99.376206ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:50:27.704322Z","caller":"traceutil/trace.go:171","msg":"trace[283503685] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"156.729368ms","start":"2026-04-23T13:50:27.547571Z","end":"2026-04-23T13:50:27.704301Z","steps":["trace[283503685] 'process raft request' (duration: 156.682657ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:50:27.704356Z","caller":"traceutil/trace.go:171","msg":"trace[649936516] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"218.51522ms","start":"2026-04-23T13:50:27.485813Z","end":"2026-04-23T13:50:27.704328Z","steps":["trace[649936516] 'process raft request' (duration: 119.548647ms)","trace[649936516] 'compare' (duration: 98.749966ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:50:37.556528Z","caller":"traceutil/trace.go:171","msg":"trace[1749125475] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"283.537553ms","start":"2026-04-23T13:50:37.272969Z","end":"2026-04-23T13:50:37.556507Z","steps":["trace[1749125475] 'read index received' (duration: 283.441319ms)","trace[1749125475] 'applied index is now lower than readState.Index' (duration: 95.323µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:50:37.55656Z","caller":"traceutil/trace.go:171","msg":"trace[687247641] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"326.757243ms","start":"2026-04-23T13:50:37.229772Z","end":"2026-04-23T13:50:37.55653Z","steps":["trace[687247641] 'process raft request' (duration: 326.606277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:37.556714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.721938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T13:50:37.556752Z","caller":"traceutil/trace.go:171","msg":"trace[1847651550] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:965; }","duration":"283.809652ms","start":"2026-04-23T13:50:37.272933Z","end":"2026-04-23T13:50:37.556743Z","steps":["trace[1847651550] 'agreement among raft nodes before linearized reading' (duration: 283.701709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:37.556894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.866892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T13:50:37.556929Z","caller":"traceutil/trace.go:171","msg":"trace[371047702] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:965; }","duration":"265.945433ms","start":"2026-04-23T13:50:37.290976Z","end":"2026-04-23T13:50:37.556921Z","steps":["trace[371047702] 'agreement among raft nodes before linearized reading' (duration: 265.858031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:50:37.557299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:50:37.229751Z","time spent":"326.882958ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T13:51:18.294513Z","caller":"traceutil/trace.go:171","msg":"trace[1708837216] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"120.810449ms","start":"2026-04-23T13:51:18.173679Z","end":"2026-04-23T13:51:18.29449Z","steps":["trace[1708837216] 'process raft request' (duration: 119.840733ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:51:18.294844Z","caller":"traceutil/trace.go:171","msg":"trace[2071621162] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"120.719715ms","start":"2026-04-23T13:51:18.174104Z","end":"2026-04-23T13:51:18.294824Z","steps":["trace[2071621162] 'process raft request' (duration: 120.343302ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:51:18.294896Z","caller":"traceutil/trace.go:171","msg":"trace[1648615243] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"120.756957ms","start":"2026-04-23T13:51:18.174134Z","end":"2026-04-23T13:51:18.29489Z","steps":["trace[1648615243] 'process raft request' (duration: 120.524219ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:52:02.62376Z","caller":"traceutil/trace.go:171","msg":"trace[387305290] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"103.482519ms","start":"2026-04-23T13:52:02.520258Z","end":"2026-04-23T13:52:02.62374Z","steps":["trace[387305290] 'read index received' (duration: 62.487473ms)","trace[387305290] 'applied index is now lower than readState.Index' (duration: 40.993936ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:52:02.623841Z","caller":"traceutil/trace.go:171","msg":"trace[798502833] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"140.287777ms","start":"2026-04-23T13:52:02.48353Z","end":"2026-04-23T13:52:02.623818Z","steps":["trace[798502833] 'process raft request' (duration: 99.25716ms)","trace[798502833] 'compare' (duration: 40.853551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:52:02.623998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.72953ms","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-23T13:52:02.624057Z","caller":"traceutil/trace.go:171","msg":"trace[86145654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1278; }","duration":"103.825633ms","start":"2026-04-23T13:52:02.520219Z","end":"2026-04-23T13:52:02.624044Z","steps":["trace[86145654] 'agreement among raft nodes before linearized reading' (duration: 103.642467ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:52:04.908637Z","caller":"traceutil/trace.go:171","msg":"trace[1590029621] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1349; }","duration":"167.708265ms","start":"2026-04-23T13:52:04.740879Z","end":"2026-04-23T13:52:04.908587Z","steps":["trace[1590029621] 'read index received' (duration: 167.121572ms)","trace[1590029621] 'applied index is now lower than readState.Index' (duration: 583.203µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:52:04.908745Z","caller":"traceutil/trace.go:171","msg":"trace[1966377068] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"214.674023ms","start":"2026-04-23T13:52:04.694034Z","end":"2026-04-23T13:52:04.908708Z","steps":["trace[1966377068] 'process raft request' (duration: 213.981846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:52:04.908807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.905123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:52:04.908868Z","caller":"traceutil/trace.go:171","msg":"trace[1151633293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"168.008687ms","start":"2026-04-23T13:52:04.740846Z","end":"2026-04-23T13:52:04.908854Z","steps":["trace[1151633293] 'agreement among raft nodes before linearized reading' (duration: 167.888942ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:52:04.908653Z","caller":"traceutil/trace.go:171","msg":"trace[479998975] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"213.914062ms","start":"2026-04-23T13:52:04.694713Z","end":"2026-04-23T13:52:04.908627Z","steps":["trace[479998975] 'process raft request' (duration: 213.677933ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:52:04.909235Z","caller":"traceutil/trace.go:171","msg":"trace[1771982858] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"215.009186ms","start":"2026-04-23T13:52:04.694209Z","end":"2026-04-23T13:52:04.909218Z","steps":["trace[1771982858] 'process raft request' (duration: 214.10554ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:52:05.201047Z","caller":"traceutil/trace.go:171","msg":"trace[1132340004] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"172.074515ms","start":"2026-04-23T13:52:05.028945Z","end":"2026-04-23T13:52:05.201019Z","steps":["trace[1132340004] 'process raft request' (duration: 101.191258ms)","trace[1132340004] 'compare' (duration: 70.529383ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:52:05.201256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.68217ms","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-23T13:52:05.201362Z","caller":"traceutil/trace.go:171","msg":"trace[1964490972] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1302; }","duration":"136.842247ms","start":"2026-04-23T13:52:05.064506Z","end":"2026-04-23T13:52:05.201348Z","steps":["trace[1964490972] 'agreement among raft nodes before linearized reading' (duration: 136.641439ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:52:05.201075Z","caller":"traceutil/trace.go:171","msg":"trace[1165529681] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"136.441881ms","start":"2026-04-23T13:52:05.064539Z","end":"2026-04-23T13:52:05.200981Z","steps":["trace[1165529681] 'read index received' (duration: 65.605786ms)","trace[1165529681] 'applied index is now lower than readState.Index' (duration: 70.834395ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:52:05.417268Z","caller":"traceutil/trace.go:171","msg":"trace[591028307] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"137.529723ms","start":"2026-04-23T13:52:05.279716Z","end":"2026-04-23T13:52:05.417246Z","steps":["trace[591028307] 'process raft request' (duration: 137.320995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:52:15.459601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880868928685705404,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T13:52:15.624749Z","caller":"traceutil/trace.go:171","msg":"trace[673310946] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"671.313046ms","start":"2026-04-23T13:52:14.953408Z","end":"2026-04-23T13:52:15.624721Z","steps":["trace[673310946] 'process raft request' (duration: 671.190231ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:52:15.62502Z","caller":"traceutil/trace.go:171","msg":"trace[1731218445] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1394; }","duration":"665.627128ms","start":"2026-04-23T13:52:14.959371Z","end":"2026-04-23T13:52:15.624998Z","steps":["trace[1731218445] 'read index received' (duration: 665.617097ms)","trace[1731218445] 'applied index is now lower than readState.Index' (duration: 8.25µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:52:15.625064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:52:14.953397Z","time spent":"671.453553ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:52:15.625208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.831416ms","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-23T13:52:15.625273Z","caller":"traceutil/trace.go:171","msg":"trace[2007459676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1340; }","duration":"665.9399ms","start":"2026-04-23T13:52:14.959316Z","end":"2026-04-23T13:52:15.625256Z","steps":["trace[2007459676] 'agreement among raft nodes before linearized reading' (duration: 665.749473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:52:15.625316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:52:14.959294Z","time spent":"666.014242ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T13:52:15.649299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.864285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T13:52:15.649397Z","caller":"traceutil/trace.go:171","msg":"trace[311475566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1341; }","duration":"690.053423ms","start":"2026-04-23T13:52:14.959322Z","end":"2026-04-23T13:52:15.649375Z","steps":["trace[311475566] 'agreement among raft nodes before linearized reading' (duration: 689.778753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:52:15.649442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:52:14.95929Z","time spent":"690.144057ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T13:52:47.194415Z","caller":"traceutil/trace.go:171","msg":"trace[1163964711] linearizableReadLoop","detail":"{readStateIndex:1510; appliedIndex:1509; }","duration":"153.955129ms","start":"2026-04-23T13:52:47.040432Z","end":"2026-04-23T13:52:47.194388Z","steps":["trace[1163964711] 'read index received' (duration: 92.443736ms)","trace[1163964711] 'applied index is now lower than readState.Index' (duration: 61.510333ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:52:47.194536Z","caller":"traceutil/trace.go:171","msg":"trace[2106852036] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"155.920299ms","start":"2026-04-23T13:52:47.038566Z","end":"2026-04-23T13:52:47.194486Z","steps":["trace[2106852036] 'process raft request' (duration: 94.361515ms)","trace[2106852036] 'compare' (duration: 61.342305ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:52:47.194683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.22962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6tvgp\" ","response":"range_response_count:1 size:5487"} {"level":"info","ts":"2026-04-23T13:52:47.19477Z","caller":"traceutil/trace.go:171","msg":"trace[1752413406] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6tvgp; range_end:; response_count:1; response_revision:1449; }","duration":"154.334074ms","start":"2026-04-23T13:52:47.040407Z","end":"2026-04-23T13:52:47.194741Z","steps":["trace[1752413406] 'agreement among raft nodes before linearized reading' (duration: 154.084004ms)"],"step_count":1}