{"level":"warn","ts":"2026-04-02T16:37:34.644873Z","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-02T16:37:34.644973Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-02T16:37:34.645052Z","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-02T16:37:34.645063Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-02T16:37:34.645086Z","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-02T16:37:34.645937Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-02T16:37:34.646026Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-02T16:37:34.652981Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.634793ms"} {"level":"info","ts":"2026-04-02T16:37:34.672432Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-02T16:37:34.672546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T16:37:34.672584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-02T16:37:34.67261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T16:37:34.672628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-02T16:37:34.672689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-02T16:37:34.687817Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T16:37:34.691027Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T16:37:34.694868Z","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-02T16:37:34.699284Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T16:37:34.699661Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T16:37:34.699799Z","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-02T16:37:34.699925Z","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-02T16:37:34.699942Z","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-02T16:37:34.700613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-02T16:37:34.702756Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-02T16:37:34.70674Z","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-02T16:37:34.706831Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-02T16:37:34.706876Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-02T16:37:34.707084Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T16:37:34.707123Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T16:37:35.672987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T16:37:35.67308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T16:37:35.673111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-02T16:37:35.673134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-02T16:37:35.673147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-02T16:37:35.673172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-02T16:37:35.673186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-02T16:37:35.701405Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:37:35.754003Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T16:37:35.754053Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:37:35.754152Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:37:35.754424Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T16:37:35.755313Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T16:37:35.757296Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T16:37:35.757754Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-04-02T16:37:35.765639Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:37:35.765782Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:37:35.765866Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:37:38.73547Z","caller":"traceutil/trace.go:171","msg":"trace[209796581] linearizableReadLoop","detail":"{readStateIndex:100; appliedIndex:98; }","duration":"124.372499ms","start":"2026-04-02T16:37:38.611071Z","end":"2026-04-02T16:37:38.735444Z","steps":["trace[209796581] 'read index received' (duration: 124.178827ms)","trace[209796581] 'applied index is now lower than readState.Index' (duration: 192.542µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:37:38.735572Z","caller":"traceutil/trace.go:171","msg":"trace[1034991405] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"129.249165ms","start":"2026-04-02T16:37:38.606285Z","end":"2026-04-02T16:37:38.735534Z","steps":["trace[1034991405] 'process raft request' (duration: 128.970583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:38.735694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.594331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-controller-manager\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:37:38.735764Z","caller":"traceutil/trace.go:171","msg":"trace[612469128] range","detail":"{range_begin:/registry/clusterroles/system:kube-controller-manager; range_end:; response_count:0; response_revision:96; }","duration":"124.738422ms","start":"2026-04-02T16:37:38.611013Z","end":"2026-04-02T16:37:38.735752Z","steps":["trace[612469128] 'agreement among raft nodes before linearized reading' (duration: 124.564731ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:38.735754Z","caller":"traceutil/trace.go:171","msg":"trace[247085734] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"129.260136ms","start":"2026-04-02T16:37:38.606467Z","end":"2026-04-02T16:37:38.735727Z","steps":["trace[247085734] 'process raft request' (duration: 128.927793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:40.045633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1210771s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-02T16:37:40.045756Z","caller":"traceutil/trace.go:171","msg":"trace[1402676827] linearizableReadLoop","detail":"{readStateIndex:106; appliedIndex:105; }","duration":"937.275599ms","start":"2026-04-02T16:37:39.108464Z","end":"2026-04-02T16:37:40.04574Z","steps":["trace[1402676827] 'read index received' (duration: 135.621µs)","trace[1402676827] 'applied index is now lower than readState.Index' (duration: 937.138578ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:37:40.045924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.46278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:37:40.045962Z","caller":"traceutil/trace.go:171","msg":"trace[1179675708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:102; }","duration":"937.53887ms","start":"2026-04-02T16:37:39.108413Z","end":"2026-04-02T16:37:40.045952Z","steps":["trace[1179675708] 'agreement among raft nodes before linearized reading' (duration: 937.417529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:40.046003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:39.108387Z","time spent":"937.607301ms","remote":"127.0.0.1:38974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:37:40.046094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.620358ms","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-02T16:37:40.045995Z","caller":"traceutil/trace.go:171","msg":"trace[363495506] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"1.214352829s","start":"2026-04-02T16:37:38.831608Z","end":"2026-04-02T16:37:40.045961Z","steps":["trace[363495506] 'process raft request' (duration: 92.359412ms)","trace[363495506] 'compare' (duration: 1.120889568s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:37:40.046133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.197822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-02T16:37:40.046239Z","caller":"traceutil/trace.go:171","msg":"trace[213290196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:102; }","duration":"135.816809ms","start":"2026-04-02T16:37:39.910395Z","end":"2026-04-02T16:37:40.046212Z","steps":["trace[213290196] 'agreement among raft nodes before linearized reading' (duration: 135.531477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:40.046128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.611642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:37:40.046407Z","caller":"traceutil/trace.go:171","msg":"trace[1730517259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:102; }","duration":"706.955914ms","start":"2026-04-02T16:37:39.339411Z","end":"2026-04-02T16:37:40.046367Z","steps":["trace[1730517259] 'agreement among raft nodes before linearized reading' (duration: 706.523831ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:40.046313Z","caller":"traceutil/trace.go:171","msg":"trace[850244941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:102; }","duration":"136.455294ms","start":"2026-04-02T16:37:39.909824Z","end":"2026-04-02T16:37:40.04628Z","steps":["trace[850244941] 'agreement among raft nodes before linearized reading' (duration: 136.154071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:40.046542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:39.339392Z","time spent":"707.132635ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:37:40.04655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:38.831593Z","time spent":"1.214748152s","remote":"127.0.0.1:39340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":710,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:37:40.415449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.298123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-02T16:37:40.415678Z","caller":"traceutil/trace.go:171","msg":"trace[276244850] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"363.984403ms","start":"2026-04-02T16:37:40.051663Z","end":"2026-04-02T16:37:40.415647Z","steps":["trace[276244850] 'process raft request' (duration: 263.128215ms)","trace[276244850] 'compare' (duration: 100.139982ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:37:40.415776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:40.051626Z","time spent":"364.111884ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:37:40.416364Z","caller":"traceutil/trace.go:171","msg":"trace[247334711] transaction","detail":"{read_only:false; response_revision:104; number_of_response:1; }","duration":"364.585907ms","start":"2026-04-02T16:37:40.051766Z","end":"2026-04-02T16:37:40.416351Z","steps":["trace[247334711] 'process raft request' (duration: 363.834512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:40.416441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:40.051744Z","time spent":"364.657108ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:37:40.416465Z","caller":"traceutil/trace.go:171","msg":"trace[467615371] transaction","detail":"{read_only:false; response_revision:105; number_of_response:1; }","duration":"362.742784ms","start":"2026-04-02T16:37:40.053687Z","end":"2026-04-02T16:37:40.416429Z","steps":["trace[467615371] 'process raft request' (duration: 362.594563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:40.416635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:40.053664Z","time spent":"362.893275ms","remote":"127.0.0.1:39340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":666,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:37:40.417237Z","caller":"traceutil/trace.go:171","msg":"trace[2090295975] linearizableReadLoop","detail":"{readStateIndex:109; appliedIndex:106; }","duration":"307.078899ms","start":"2026-04-02T16:37:40.109989Z","end":"2026-04-02T16:37:40.417068Z","steps":["trace[2090295975] 'read index received' (duration: 204.82234ms)","trace[2090295975] 'applied index is now lower than readState.Index' (duration: 102.254679ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:37:40.417545Z","caller":"traceutil/trace.go:171","msg":"trace[617255941] transaction","detail":"{read_only:false; response_revision:106; number_of_response:1; }","duration":"201.524665ms","start":"2026-04-02T16:37:40.216003Z","end":"2026-04-02T16:37:40.417528Z","steps":["trace[617255941] 'process raft request' (duration: 200.619668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:40.418047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.530102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:37:40.418111Z","caller":"traceutil/trace.go:171","msg":"trace[1718148515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:106; }","duration":"308.164258ms","start":"2026-04-02T16:37:40.109926Z","end":"2026-04-02T16:37:40.41809Z","steps":["trace[1718148515] 'agreement among raft nodes before linearized reading' (duration: 307.404531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:40.418365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:40.1099Z","time spent":"308.387829ms","remote":"127.0.0.1:38974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T16:37:40.458313Z","caller":"traceutil/trace.go:171","msg":"trace[700957608] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"104.660707ms","start":"2026-04-02T16:37:40.353632Z","end":"2026-04-02T16:37:40.458292Z","steps":["trace[700957608] 'process raft request' (duration: 104.293435ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:40.671432Z","caller":"traceutil/trace.go:171","msg":"trace[956047255] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"209.820238ms","start":"2026-04-02T16:37:40.46158Z","end":"2026-04-02T16:37:40.6714Z","steps":["trace[956047255] 'process raft request' (duration: 175.686569ms)","trace[956047255] 'compare' (duration: 33.951468ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:37:40.94393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.523806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-02T16:37:40.944018Z","caller":"traceutil/trace.go:171","msg":"trace[2094897753] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"228.122488ms","start":"2026-04-02T16:37:40.71588Z","end":"2026-04-02T16:37:40.944003Z","steps":["trace[2094897753] 'process raft request' (duration: 60.462611ms)","trace[2094897753] 'compare' (duration: 167.399655ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:37:41.315728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.1071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-02T16:37:41.315894Z","caller":"traceutil/trace.go:171","msg":"trace[655679633] linearizableReadLoop","detail":"{readStateIndex:116; appliedIndex:115; }","duration":"177.461755ms","start":"2026-04-02T16:37:41.138407Z","end":"2026-04-02T16:37:41.315869Z","steps":["trace[655679633] 'read index received' (duration: 53.124663ms)","trace[655679633] 'applied index is now lower than readState.Index' (duration: 124.334822ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:37:41.316005Z","caller":"traceutil/trace.go:171","msg":"trace[1715661931] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"335.512484ms","start":"2026-04-02T16:37:40.980435Z","end":"2026-04-02T16:37:41.315947Z","steps":["trace[1715661931] 'process raft request' (duration: 211.083302ms)","trace[1715661931] 'compare' (duration: 123.698897ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:37:41.316195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:40.980416Z","time spent":"335.676655ms","remote":"127.0.0.1:39340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":699,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:37:41.316212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.799637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:37:41.316251Z","caller":"traceutil/trace.go:171","msg":"trace[200848830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:112; }","duration":"177.883877ms","start":"2026-04-02T16:37:41.138355Z","end":"2026-04-02T16:37:41.316239Z","steps":["trace[200848830] 'agreement among raft nodes before linearized reading' (duration: 177.659296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:42.539834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.558735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:37:42.539919Z","caller":"traceutil/trace.go:171","msg":"trace[823764321] linearizableReadLoop","detail":"{readStateIndex:165; appliedIndex:164; }","duration":"119.680292ms","start":"2026-04-02T16:37:42.420226Z","end":"2026-04-02T16:37:42.539907Z","steps":["trace[823764321] 'read index received' (duration: 52.48µs)","trace[823764321] 'applied index is now lower than readState.Index' (duration: 119.627162ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:37:42.539989Z","caller":"traceutil/trace.go:171","msg":"trace[1415443970] transaction","detail":"{read_only:false; response_revision:161; number_of_response:1; }","duration":"205.205006ms","start":"2026-04-02T16:37:42.334741Z","end":"2026-04-02T16:37:42.539946Z","steps":["trace[1415443970] 'process raft request' (duration: 54.48297ms)","trace[1415443970] 'compare' (duration: 150.415914ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:37:42.540072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.838223ms","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-02T16:37:42.540102Z","caller":"traceutil/trace.go:171","msg":"trace[87326942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:161; }","duration":"119.934434ms","start":"2026-04-02T16:37:42.420151Z","end":"2026-04-02T16:37:42.540086Z","steps":["trace[87326942] 'agreement among raft nodes before linearized reading' (duration: 119.814023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:42.540134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.830355ms","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-02T16:37:42.540196Z","caller":"traceutil/trace.go:171","msg":"trace[1092379833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:161; }","duration":"118.911246ms","start":"2026-04-02T16:37:42.421271Z","end":"2026-04-02T16:37:42.540182Z","steps":["trace[1092379833] 'agreement among raft nodes before linearized reading' (duration: 118.781755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:42.540201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.640756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:37:42.540298Z","caller":"traceutil/trace.go:171","msg":"trace[1256297673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:161; }","duration":"101.774507ms","start":"2026-04-02T16:37:42.438499Z","end":"2026-04-02T16:37:42.540273Z","steps":["trace[1256297673] 'agreement among raft nodes before linearized reading' (duration: 101.606985ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:42.670625Z","caller":"traceutil/trace.go:171","msg":"trace[330126030] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"125.116463ms","start":"2026-04-02T16:37:42.545472Z","end":"2026-04-02T16:37:42.670588Z","steps":["trace[330126030] 'process raft request' (duration: 110.325211ms)","trace[330126030] 'compare' (duration: 14.566101ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:37:42.670946Z","caller":"traceutil/trace.go:171","msg":"trace[1008072338] transaction","detail":"{read_only:false; response_revision:164; number_of_response:1; }","duration":"125.176654ms","start":"2026-04-02T16:37:42.545537Z","end":"2026-04-02T16:37:42.670714Z","steps":["trace[1008072338] 'process raft request' (duration: 124.971163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:43.881405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.046115ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-04-02T16:37:43.881756Z","caller":"traceutil/trace.go:171","msg":"trace[508918112] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:211; }","duration":"172.230393ms","start":"2026-04-02T16:37:43.70951Z","end":"2026-04-02T16:37:43.88174Z","steps":["trace[508918112] 'read index received' (duration: 53.778875ms)","trace[508918112] 'applied index is now lower than readState.Index' (duration: 118.450808ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:37:43.881828Z","caller":"traceutil/trace.go:171","msg":"trace[1552884740] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"223.673787ms","start":"2026-04-02T16:37:43.658144Z","end":"2026-04-02T16:37:43.881818Z","steps":["trace[1552884740] 'process raft request' (duration: 223.360145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:43.882248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.14793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:37:43.882374Z","caller":"traceutil/trace.go:171","msg":"trace[381342278] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:208; }","duration":"136.349812ms","start":"2026-04-02T16:37:43.745996Z","end":"2026-04-02T16:37:43.882345Z","steps":["trace[381342278] 'agreement among raft nodes before linearized reading' (duration: 135.904349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:43.882269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.730586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5895"} {"level":"info","ts":"2026-04-02T16:37:43.882492Z","caller":"traceutil/trace.go:171","msg":"trace[1694502038] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:208; }","duration":"173.021398ms","start":"2026-04-02T16:37:43.709456Z","end":"2026-04-02T16:37:43.882477Z","steps":["trace[1694502038] 'agreement among raft nodes before linearized reading' (duration: 172.475674ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:44.528332Z","caller":"traceutil/trace.go:171","msg":"trace[1662754973] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"109.687878ms","start":"2026-04-02T16:37:44.418596Z","end":"2026-04-02T16:37:44.528284Z","steps":["trace[1662754973] 'process raft request' (duration: 43.288702ms)","trace[1662754973] 'compare' (duration: 66.197435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:37:45.663657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409463135785615,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T16:37:46.010952Z","caller":"traceutil/trace.go:171","msg":"trace[657440689] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"847.755854ms","start":"2026-04-02T16:37:45.163173Z","end":"2026-04-02T16:37:46.010929Z","steps":["trace[657440689] 'read index received' (duration: 847.576563ms)","trace[657440689] 'applied index is now lower than readState.Index' (duration: 178.341µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:37:46.011062Z","caller":"traceutil/trace.go:171","msg":"trace[1653859900] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"879.209508ms","start":"2026-04-02T16:37:45.131825Z","end":"2026-04-02T16:37:46.011034Z","steps":["trace[1653859900] 'process raft request' (duration: 878.971977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:46.011184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:45.131788Z","time spent":"879.332579ms","remote":"127.0.0.1:39172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:37:46.011385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.202307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-04-02T16:37:46.011437Z","caller":"traceutil/trace.go:171","msg":"trace[1501087440] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:285; }","duration":"848.315299ms","start":"2026-04-02T16:37:45.163109Z","end":"2026-04-02T16:37:46.011424Z","steps":["trace[1501087440] 'agreement among raft nodes before linearized reading' (duration: 847.950706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:46.011589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:45.163082Z","time spent":"848.49156ms","remote":"127.0.0.1:39144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3731,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-02T16:37:47.614217Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.597316104s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T16:37:47.614959Z","caller":"traceutil/trace.go:171","msg":"trace[1828709994] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:291; }","duration":"1.59810369s","start":"2026-04-02T16:37:46.016817Z","end":"2026-04-02T16:37:47.614921Z","steps":["trace[1828709994] 'read index received' (duration: 74.59µs)","trace[1828709994] 'applied index is now lower than readState.Index' (duration: 1.59802754s)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:37:47.615032Z","caller":"traceutil/trace.go:171","msg":"trace[693921587] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"1.598194371s","start":"2026-04-02T16:37:46.016791Z","end":"2026-04-02T16:37:47.614986Z","steps":["trace[693921587] 'process raft request' (duration: 1.597902109s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:47.615236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:46.016774Z","time spent":"1.598350332s","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:37:47.615438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598497663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:37:47.615508Z","caller":"traceutil/trace.go:171","msg":"trace[100095763] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:286; }","duration":"1.598713364s","start":"2026-04-02T16:37:46.016776Z","end":"2026-04-02T16:37:47.61549Z","steps":["trace[100095763] 'agreement among raft nodes before linearized reading' (duration: 1.598438512s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:47.615734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:46.01676Z","time spent":"1.598902956s","remote":"127.0.0.1:39172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" "} {"level":"info","ts":"2026-04-02T16:37:47.978411Z","caller":"traceutil/trace.go:171","msg":"trace[1767048366] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"1.959005214s","start":"2026-04-02T16:37:46.019367Z","end":"2026-04-02T16:37:47.978372Z","steps":["trace[1767048366] 'process raft request' (duration: 1.958672002s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:47.978464Z","caller":"traceutil/trace.go:171","msg":"trace[1343867479] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"1.298583524s","start":"2026-04-02T16:37:46.67985Z","end":"2026-04-02T16:37:47.978434Z","steps":["trace[1343867479] 'process raft request' (duration: 1.298417472s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:47.978587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:46.019351Z","time spent":"1.959138715s","remote":"127.0.0.1:39144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:37:47.978792Z","caller":"traceutil/trace.go:171","msg":"trace[1650938897] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:292; }","duration":"363.695875ms","start":"2026-04-02T16:37:47.615059Z","end":"2026-04-02T16:37:47.978754Z","steps":["trace[1650938897] 'read index received' (duration: 363.03091ms)","trace[1650938897] 'applied index is now lower than readState.Index' (duration: 663.555µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:37:47.978898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.041692ms","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-02T16:37:47.97895Z","caller":"traceutil/trace.go:171","msg":"trace[894855433] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"633.806273ms","start":"2026-04-02T16:37:47.345132Z","end":"2026-04-02T16:37:47.978939Z","steps":["trace[894855433] 'process raft request' (duration: 633.273978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:47.978981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280652282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:37:47.978992Z","caller":"traceutil/trace.go:171","msg":"trace[1187606890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:289; }","duration":"991.177492ms","start":"2026-04-02T16:37:46.98779Z","end":"2026-04-02T16:37:47.978968Z","steps":["trace[1187606890] 'agreement among raft nodes before linearized reading' (duration: 991.008801ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:47.979015Z","caller":"traceutil/trace.go:171","msg":"trace[1224872735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:289; }","duration":"1.280743663s","start":"2026-04-02T16:37:46.698262Z","end":"2026-04-02T16:37:47.979005Z","steps":["trace[1224872735] 'agreement among raft nodes before linearized reading' (duration: 1.280644152s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:47.97895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.709149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:37:47.979045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:46.698242Z","time spent":"1.280796963s","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T16:37:47.979044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:46.98777Z","time spent":"991.262753ms","remote":"127.0.0.1:39226","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":"info","ts":"2026-04-02T16:37:47.979077Z","caller":"traceutil/trace.go:171","msg":"trace[1593041768] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:289; }","duration":"759.903191ms","start":"2026-04-02T16:37:47.219154Z","end":"2026-04-02T16:37:47.979057Z","steps":["trace[1593041768] 'agreement among raft nodes before linearized reading' (duration: 759.624799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:47.979121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:47.219128Z","time spent":"759.985731ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-02T16:37:47.979124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379770964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:37:47.979235Z","caller":"traceutil/trace.go:171","msg":"trace[24339022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:289; }","duration":"1.379943356s","start":"2026-04-02T16:37:46.599265Z","end":"2026-04-02T16:37:47.979209Z","steps":["trace[24339022] 'agreement among raft nodes before linearized reading' (duration: 1.379709934s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:47.979294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:46.599237Z","time spent":"1.380047046s","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T16:37:47.97932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:46.679818Z","time spent":"1.298733004s","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:37:47.979018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:47.345091Z","time spent":"633.896423ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:37:48.093906Z","caller":"traceutil/trace.go:171","msg":"trace[132959515] transaction","detail":"{read_only:false; number_of_response:1; response_revision:289; }","duration":"441.587716ms","start":"2026-04-02T16:37:47.652283Z","end":"2026-04-02T16:37:48.093871Z","steps":["trace[132959515] 'process raft request' (duration: 435.532361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:48.093954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.348318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:37:48.094232Z","caller":"traceutil/trace.go:171","msg":"trace[457132449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:289; }","duration":"292.51526ms","start":"2026-04-02T16:37:47.801496Z","end":"2026-04-02T16:37:48.094011Z","steps":["trace[457132449] 'agreement among raft nodes before linearized reading' (duration: 292.373769ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:48.094335Z","caller":"traceutil/trace.go:171","msg":"trace[1589214249] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"109.884375ms","start":"2026-04-02T16:37:47.984439Z","end":"2026-04-02T16:37:48.094323Z","steps":["trace[1589214249] 'process raft request' (duration: 109.685374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:48.094457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.74687ms","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-02T16:37:48.094598Z","caller":"traceutil/trace.go:171","msg":"trace[149375718] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:289; }","duration":"475.121553ms","start":"2026-04-02T16:37:47.619451Z","end":"2026-04-02T16:37:48.094573Z","steps":["trace[149375718] 'agreement among raft nodes before linearized reading' (duration: 474.608409ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:48.094685Z","caller":"traceutil/trace.go:171","msg":"trace[619881060] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"109.702264ms","start":"2026-04-02T16:37:47.984963Z","end":"2026-04-02T16:37:48.094665Z","steps":["trace[619881060] 'process raft request' (duration: 109.638084ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:48.094607Z","caller":"traceutil/trace.go:171","msg":"trace[393988521] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:295; }","duration":"114.959923ms","start":"2026-04-02T16:37:47.978874Z","end":"2026-04-02T16:37:48.093833Z","steps":["trace[393988521] 'read index received' (duration: 108.965588ms)","trace[393988521] 'applied index is now lower than readState.Index' (duration: 5.993394ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:37:48.094797Z","caller":"traceutil/trace.go:171","msg":"trace[377924028] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"110.081166ms","start":"2026-04-02T16:37:47.984708Z","end":"2026-04-02T16:37:48.094789Z","steps":["trace[377924028] 'process raft request' (duration: 109.587403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:48.094883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:47.652249Z","time spent":"441.736857ms","remote":"127.0.0.1:39144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30,"response count":0,"response size":4050,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:37:48.095056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.038844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-vpuxqa\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:37:48.095078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.227835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-02T16:37:48.095106Z","caller":"traceutil/trace.go:171","msg":"trace[884485473] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-vpuxqa; range_end:; response_count:0; response_revision:292; }","duration":"111.197285ms","start":"2026-04-02T16:37:47.983896Z","end":"2026-04-02T16:37:48.095094Z","steps":["trace[884485473] 'agreement among raft nodes before linearized reading' (duration: 111.030244ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:48.095202Z","caller":"traceutil/trace.go:171","msg":"trace[1387500311] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:292; }","duration":"111.439527ms","start":"2026-04-02T16:37:47.983699Z","end":"2026-04-02T16:37:48.095139Z","steps":["trace[1387500311] 'agreement among raft nodes before linearized reading' (duration: 111.216005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:48.094729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:47.61942Z","time spent":"475.295754ms","remote":"127.0.0.1:39094","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":"warn","ts":"2026-04-02T16:38:03.832718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.916348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:38:03.832877Z","caller":"traceutil/trace.go:171","msg":"trace[681319042] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"112.059048ms","start":"2026-04-02T16:38:03.720779Z","end":"2026-04-02T16:38:03.832838Z","steps":["trace[681319042] 'read index received' (duration: 51.011µs)","trace[681319042] 'applied index is now lower than readState.Index' (duration: 112.006647ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:03.832925Z","caller":"traceutil/trace.go:171","msg":"trace[64278661] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"191.533291ms","start":"2026-04-02T16:38:03.641353Z","end":"2026-04-02T16:38:03.832886Z","steps":["trace[64278661] 'process raft request' (duration: 67.327805ms)","trace[64278661] 'compare' (duration: 123.681622ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:03.833108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.332405ms","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-02T16:38:03.833163Z","caller":"traceutil/trace.go:171","msg":"trace[1643294599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:477; }","duration":"112.425558ms","start":"2026-04-02T16:38:03.720722Z","end":"2026-04-02T16:38:03.833148Z","steps":["trace[1643294599] 'agreement among raft nodes before linearized reading' (duration: 112.201422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:04.69212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409463135786015,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T16:38:04.747118Z","caller":"traceutil/trace.go:171","msg":"trace[1371660654] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"556.325933ms","start":"2026-04-02T16:38:04.190751Z","end":"2026-04-02T16:38:04.747077Z","steps":["trace[1371660654] 'process raft request' (duration: 556.110507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:04.747344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:04.19073Z","time spent":"556.468607ms","remote":"127.0.0.1:39226","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-02T16:38:04.801775Z","caller":"traceutil/trace.go:171","msg":"trace[90780479] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:498; }","duration":"610.668094ms","start":"2026-04-02T16:38:04.191079Z","end":"2026-04-02T16:38:04.801747Z","steps":["trace[90780479] 'read index received' (duration: 555.835141ms)","trace[90780479] 'applied index is now lower than readState.Index' (duration: 54.831743ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:04.801926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.846028ms","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-02T16:38:04.801978Z","caller":"traceutil/trace.go:171","msg":"trace[400931733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:480; }","duration":"610.92579ms","start":"2026-04-02T16:38:04.191035Z","end":"2026-04-02T16:38:04.801961Z","steps":["trace[400931733] 'agreement among raft nodes before linearized reading' (duration: 610.816857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:04.802009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:04.191022Z","time spent":"610.979051ms","remote":"127.0.0.1:39226","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-02T16:38:04.802115Z","caller":"traceutil/trace.go:171","msg":"trace[455829130] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"609.718089ms","start":"2026-04-02T16:38:04.192387Z","end":"2026-04-02T16:38:04.802105Z","steps":["trace[455829130] 'process raft request' (duration: 609.235597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:04.802214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:04.192372Z","time spent":"609.78594ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:38:04.978115Z","caller":"traceutil/trace.go:171","msg":"trace[1603164861] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"171.776127ms","start":"2026-04-02T16:38:04.806308Z","end":"2026-04-02T16:38:04.978084Z","steps":["trace[1603164861] 'process raft request' (duration: 110.457406ms)","trace[1603164861] 'compare' (duration: 61.165437ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:10.891766Z","caller":"traceutil/trace.go:171","msg":"trace[1516555919] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"116.945702ms","start":"2026-04-02T16:38:10.774778Z","end":"2026-04-02T16:38:10.891724Z","steps":["trace[1516555919] 'process raft request' (duration: 116.504482ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:11.824486Z","caller":"traceutil/trace.go:171","msg":"trace[1727253105] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"240.732474ms","start":"2026-04-02T16:38:11.583727Z","end":"2026-04-02T16:38:11.824459Z","steps":["trace[1727253105] 'process raft request' (duration: 240.403256ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:12.076633Z","caller":"traceutil/trace.go:171","msg":"trace[946351637] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:517; }","duration":"198.047535ms","start":"2026-04-02T16:38:11.878539Z","end":"2026-04-02T16:38:12.076587Z","steps":["trace[946351637] 'read index received' (duration: 20.918834ms)","trace[946351637] 'applied index is now lower than readState.Index' (duration: 177.126631ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:12.07671Z","caller":"traceutil/trace.go:171","msg":"trace[1190937928] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"226.645095ms","start":"2026-04-02T16:38:11.850011Z","end":"2026-04-02T16:38:12.076656Z","steps":["trace[1190937928] 'process raft request' (duration: 169.903448ms)","trace[1190937928] 'compare' (duration: 56.357638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:12.076901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.348992ms","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-02T16:38:12.077041Z","caller":"traceutil/trace.go:171","msg":"trace[1929756350] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:498; }","duration":"198.496006ms","start":"2026-04-02T16:38:11.878521Z","end":"2026-04-02T16:38:12.077017Z","steps":["trace[1929756350] 'agreement among raft nodes before linearized reading' (duration: 198.24205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:12.624337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.277545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:38:12.624597Z","caller":"traceutil/trace.go:171","msg":"trace[1692404555] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:521; }","duration":"138.178814ms","start":"2026-04-02T16:38:12.486397Z","end":"2026-04-02T16:38:12.624576Z","steps":["trace[1692404555] 'read index received' (duration: 56.322µs)","trace[1692404555] 'applied index is now lower than readState.Index' (duration: 138.121422ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:12.624726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.312886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:12.624756Z","caller":"traceutil/trace.go:171","msg":"trace[1747689399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"138.409439ms","start":"2026-04-02T16:38:12.486333Z","end":"2026-04-02T16:38:12.624743Z","steps":["trace[1747689399] 'agreement among raft nodes before linearized reading' (duration: 138.298776ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:12.624917Z","caller":"traceutil/trace.go:171","msg":"trace[1533004340] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"495.754803ms","start":"2026-04-02T16:38:12.129097Z","end":"2026-04-02T16:38:12.624852Z","steps":["trace[1533004340] 'process raft request' (duration: 226.070725ms)","trace[1533004340] 'compare' (duration: 268.0576ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:12.624961Z","caller":"traceutil/trace.go:171","msg":"trace[1370303386] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"495.318791ms","start":"2026-04-02T16:38:12.129586Z","end":"2026-04-02T16:38:12.624905Z","steps":["trace[1370303386] 'process raft request' (duration: 494.868181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:12.625107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:12.129522Z","time spent":"495.519287ms","remote":"127.0.0.1:39340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1639,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:38:12.625115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:12.129061Z","time spent":"495.960237ms","remote":"127.0.0.1:39340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:38:12.892723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.666335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:38:12.892931Z","caller":"traceutil/trace.go:171","msg":"trace[1551074362] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"262.080815ms","start":"2026-04-02T16:38:12.630831Z","end":"2026-04-02T16:38:12.892912Z","steps":["trace[1551074362] 'process raft request' (duration: 262.006093ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:12.892981Z","caller":"traceutil/trace.go:171","msg":"trace[635967396] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"262.729282ms","start":"2026-04-02T16:38:12.630207Z","end":"2026-04-02T16:38:12.892936Z","steps":["trace[635967396] 'process raft request' (duration: 146.576845ms)","trace[635967396] 'compare' (duration: 115.479421ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:13.431508Z","caller":"traceutil/trace.go:171","msg":"trace[74339704] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:527; }","duration":"412.296949ms","start":"2026-04-02T16:38:13.019175Z","end":"2026-04-02T16:38:13.431472Z","steps":["trace[74339704] 'read index received' (duration: 324.615074ms)","trace[74339704] 'applied index is now lower than readState.Index' (duration: 87.680215ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:13.431995Z","caller":"traceutil/trace.go:171","msg":"trace[180833330] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"501.828005ms","start":"2026-04-02T16:38:12.930125Z","end":"2026-04-02T16:38:13.431953Z","steps":["trace[180833330] 'process raft request' (duration: 413.647709ms)","trace[180833330] 'compare' (duration: 87.131042ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:13.432052Z","caller":"traceutil/trace.go:171","msg":"trace[1865628841] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"460.353025ms","start":"2026-04-02T16:38:12.971678Z","end":"2026-04-02T16:38:13.432031Z","steps":["trace[1865628841] 'process raft request' (duration: 459.651668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:13.432181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:12.930106Z","time spent":"501.988591ms","remote":"127.0.0.1:39326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:38:13.432211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:12.971654Z","time spent":"460.451237ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:38:13.432251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.076369ms","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-02T16:38:13.432296Z","caller":"traceutil/trace.go:171","msg":"trace[108080035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"413.175801ms","start":"2026-04-02T16:38:13.019107Z","end":"2026-04-02T16:38:13.432283Z","steps":["trace[108080035] 'agreement among raft nodes before linearized reading' (duration: 412.492874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:13.432328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:13.019073Z","time spent":"413.247873ms","remote":"127.0.0.1:39226","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-02T16:38:15.241215Z","caller":"traceutil/trace.go:171","msg":"trace[215284579] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"162.88235ms","start":"2026-04-02T16:38:15.0783Z","end":"2026-04-02T16:38:15.241182Z","steps":["trace[215284579] 'process raft request' (duration: 162.713376ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:15.250053Z","caller":"traceutil/trace.go:171","msg":"trace[1435258394] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"157.056635ms","start":"2026-04-02T16:38:15.09297Z","end":"2026-04-02T16:38:15.250027Z","steps":["trace[1435258394] 'process raft request' (duration: 156.611065ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:15.250288Z","caller":"traceutil/trace.go:171","msg":"trace[1050063834] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"132.482715ms","start":"2026-04-02T16:38:15.11778Z","end":"2026-04-02T16:38:15.250262Z","steps":["trace[1050063834] 'process raft request' (duration: 132.220778ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:15.250349Z","caller":"traceutil/trace.go:171","msg":"trace[1223259083] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"130.600401ms","start":"2026-04-02T16:38:15.119735Z","end":"2026-04-02T16:38:15.250335Z","steps":["trace[1223259083] 'process raft request' (duration: 130.399836ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:15.250445Z","caller":"traceutil/trace.go:171","msg":"trace[1959610161] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"130.730694ms","start":"2026-04-02T16:38:15.119698Z","end":"2026-04-02T16:38:15.250429Z","steps":["trace[1959610161] 'process raft request' (duration: 130.377726ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:15.250457Z","caller":"traceutil/trace.go:171","msg":"trace[1036292063] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"128.83968ms","start":"2026-04-02T16:38:15.121572Z","end":"2026-04-02T16:38:15.250412Z","steps":["trace[1036292063] 'process raft request' (duration: 128.670546ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:15.25072Z","caller":"traceutil/trace.go:171","msg":"trace[1364091285] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"128.974083ms","start":"2026-04-02T16:38:15.121715Z","end":"2026-04-02T16:38:15.250689Z","steps":["trace[1364091285] 'process raft request' (duration: 128.581564ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:15.25085Z","caller":"traceutil/trace.go:171","msg":"trace[1731556965] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"118.122811ms","start":"2026-04-02T16:38:15.132699Z","end":"2026-04-02T16:38:15.250822Z","steps":["trace[1731556965] 'process raft request' (duration: 117.65677ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:15.251032Z","caller":"traceutil/trace.go:171","msg":"trace[1400365335] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"117.441096ms","start":"2026-04-02T16:38:15.133556Z","end":"2026-04-02T16:38:15.250997Z","steps":["trace[1400365335] 'process raft request' (duration: 116.984776ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:15.251443Z","caller":"traceutil/trace.go:171","msg":"trace[1193526173] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"145.843195ms","start":"2026-04-02T16:38:15.105402Z","end":"2026-04-02T16:38:15.251245Z","steps":["trace[1193526173] 'process raft request' (duration: 144.532144ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:21.421352Z","caller":"traceutil/trace.go:171","msg":"trace[538561993] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"272.388786ms","start":"2026-04-02T16:38:21.148932Z","end":"2026-04-02T16:38:21.421321Z","steps":["trace[538561993] 'process raft request' (duration: 270.072096ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:22.580368Z","caller":"traceutil/trace.go:171","msg":"trace[536246327] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"927.102979ms","start":"2026-04-02T16:38:21.653237Z","end":"2026-04-02T16:38:22.58034Z","steps":["trace[536246327] 'process raft request' (duration: 846.766145ms)","trace[536246327] 'compare' (duration: 80.232922ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:22.580656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:21.653222Z","time spent":"927.356694ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:38:22.60641Z","caller":"traceutil/trace.go:171","msg":"trace[1405948882] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:662; }","duration":"415.136708ms","start":"2026-04-02T16:38:22.191247Z","end":"2026-04-02T16:38:22.606383Z","steps":["trace[1405948882] 'read index received' (duration: 308.820872ms)","trace[1405948882] 'applied index is now lower than readState.Index' (duration: 106.314876ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:22.606535Z","caller":"traceutil/trace.go:171","msg":"trace[1566761693] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"623.266399ms","start":"2026-04-02T16:38:21.983216Z","end":"2026-04-02T16:38:22.606482Z","steps":["trace[1566761693] 'process raft request' (duration: 622.569773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:22.60672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.359942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:38:22.606795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:21.983187Z","time spent":"623.466613ms","remote":"127.0.0.1:39070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:38:22.606831Z","caller":"traceutil/trace.go:171","msg":"trace[748246389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"120.540396ms","start":"2026-04-02T16:38:22.486274Z","end":"2026-04-02T16:38:22.606814Z","steps":["trace[748246389] 'agreement among raft nodes before linearized reading' (duration: 120.331651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:22.606727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.415834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-02T16:38:22.607014Z","caller":"traceutil/trace.go:171","msg":"trace[1901675864] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"415.789603ms","start":"2026-04-02T16:38:22.191172Z","end":"2026-04-02T16:38:22.606961Z","steps":["trace[1901675864] 'agreement among raft nodes before linearized reading' (duration: 415.339283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:22.607112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:22.191149Z","time spent":"415.915405ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T16:38:23.549721Z","caller":"traceutil/trace.go:171","msg":"trace[975228888] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"934.429655ms","start":"2026-04-02T16:38:22.615265Z","end":"2026-04-02T16:38:23.549695Z","steps":["trace[975228888] 'process raft request' (duration: 934.293682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:23.549866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:22.615242Z","time spent":"934.553587ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:38:24.171594Z","caller":"traceutil/trace.go:171","msg":"trace[1354316640] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"1.110789923s","start":"2026-04-02T16:38:23.060775Z","end":"2026-04-02T16:38:24.171565Z","steps":["trace[1354316640] 'read index received' (duration: 488.842978ms)","trace[1354316640] 'applied index is now lower than readState.Index' (duration: 621.945765ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:24.171953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.608083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T16:38:24.172012Z","caller":"traceutil/trace.go:171","msg":"trace[391854511] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:640; }","duration":"631.716965ms","start":"2026-04-02T16:38:23.540281Z","end":"2026-04-02T16:38:24.171998Z","steps":["trace[391854511] 'agreement among raft nodes before linearized reading' (duration: 631.559482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:24.172051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:23.540264Z","time spent":"631.778786ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"info","ts":"2026-04-02T16:38:24.172061Z","caller":"traceutil/trace.go:171","msg":"trace[1543199305] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.497539653s","start":"2026-04-02T16:38:22.674495Z","end":"2026-04-02T16:38:24.172034Z","steps":["trace[1543199305] 'process raft request' (duration: 1.400855705s)","trace[1543199305] 'compare' (duration: 95.770689ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:24.172062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111264753s","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-02T16:38:24.172167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:22.674474Z","time spent":"1.497636536s","remote":"127.0.0.1:39316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:38:24.172181Z","caller":"traceutil/trace.go:171","msg":"trace[1723946680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"1.111443397s","start":"2026-04-02T16:38:23.060717Z","end":"2026-04-02T16:38:24.17216Z","steps":["trace[1723946680] 'agreement among raft nodes before linearized reading' (duration: 1.111168571s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:24.172226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:23.060693Z","time spent":"1.111522999s","remote":"127.0.0.1:39226","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-02T16:38:24.17232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.414911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:38:24.172358Z","caller":"traceutil/trace.go:171","msg":"trace[1074603155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"593.474393ms","start":"2026-04-02T16:38:23.578874Z","end":"2026-04-02T16:38:24.172348Z","steps":["trace[1074603155] 'agreement among raft nodes before linearized reading' (duration: 593.35553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:24.172386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:23.578862Z","time spent":"593.517813ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T16:38:24.172412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.725344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:38:24.172493Z","caller":"traceutil/trace.go:171","msg":"trace[2034439060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"550.843167ms","start":"2026-04-02T16:38:23.621629Z","end":"2026-04-02T16:38:24.172472Z","steps":["trace[2034439060] 'agreement among raft nodes before linearized reading' (duration: 550.437148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:24.172548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:23.621583Z","time spent":"550.95576ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T16:38:24.751536Z","caller":"traceutil/trace.go:171","msg":"trace[923319357] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"126.033454ms","start":"2026-04-02T16:38:24.62545Z","end":"2026-04-02T16:38:24.751484Z","steps":["trace[923319357] 'process raft request' (duration: 125.266058ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:24.756328Z","caller":"traceutil/trace.go:171","msg":"trace[873064734] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"102.592235ms","start":"2026-04-02T16:38:24.653725Z","end":"2026-04-02T16:38:24.756317Z","steps":["trace[873064734] 'process raft request' (duration: 102.508703ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:26.036201Z","caller":"traceutil/trace.go:171","msg":"trace[20917768] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:706; }","duration":"122.991388ms","start":"2026-04-02T16:38:25.913186Z","end":"2026-04-02T16:38:26.036178Z","steps":["trace[20917768] 'read index received' (duration: 108.360112ms)","trace[20917768] 'applied index is now lower than readState.Index' (duration: 14.630146ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:26.036278Z","caller":"traceutil/trace.go:171","msg":"trace[2007342913] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"190.605174ms","start":"2026-04-02T16:38:25.845641Z","end":"2026-04-02T16:38:26.036246Z","steps":["trace[2007342913] 'process raft request' (duration: 175.885357ms)","trace[2007342913] 'compare' (duration: 14.231227ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:26.036363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.168262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:26.036409Z","caller":"traceutil/trace.go:171","msg":"trace[689584299] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:680; }","duration":"123.227494ms","start":"2026-04-02T16:38:25.913169Z","end":"2026-04-02T16:38:26.036396Z","steps":["trace[689584299] 'agreement among raft nodes before linearized reading' (duration: 123.110181ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:29.107579Z","caller":"traceutil/trace.go:171","msg":"trace[964111138] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"170.610113ms","start":"2026-04-02T16:38:28.936947Z","end":"2026-04-02T16:38:29.107557Z","steps":["trace[964111138] 'process raft request' (duration: 170.328388ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:31.051571Z","caller":"traceutil/trace.go:171","msg":"trace[391245930] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"234.869228ms","start":"2026-04-02T16:38:30.816681Z","end":"2026-04-02T16:38:31.05155Z","steps":["trace[391245930] 'read index received' (duration: 234.643583ms)","trace[391245930] 'applied index is now lower than readState.Index' (duration: 224.785µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:31.051757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.061361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:31.051802Z","caller":"traceutil/trace.go:171","msg":"trace[1908054496] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:755; }","duration":"235.161953ms","start":"2026-04-02T16:38:30.816628Z","end":"2026-04-02T16:38:31.05179Z","steps":["trace[1908054496] 'agreement among raft nodes before linearized reading' (duration: 235.055881ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:31.051857Z","caller":"traceutil/trace.go:171","msg":"trace[1638746548] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"255.907101ms","start":"2026-04-02T16:38:30.79586Z","end":"2026-04-02T16:38:31.051767Z","steps":["trace[1638746548] 'process raft request' (duration: 255.560924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:31.051904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.207154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:31.052026Z","caller":"traceutil/trace.go:171","msg":"trace[1741977738] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:755; }","duration":"235.379058ms","start":"2026-04-02T16:38:30.816627Z","end":"2026-04-02T16:38:31.052006Z","steps":["trace[1741977738] 'agreement among raft nodes before linearized reading' (duration: 235.168453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:31.053603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.011275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:38:31.053589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.418133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:31.053749Z","caller":"traceutil/trace.go:171","msg":"trace[1373026204] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:755; }","duration":"207.647708ms","start":"2026-04-02T16:38:30.846057Z","end":"2026-04-02T16:38:31.053705Z","steps":["trace[1373026204] 'agreement among raft nodes before linearized reading' (duration: 206.643258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:31.053881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.362992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:31.054061Z","caller":"traceutil/trace.go:171","msg":"trace[2124217007] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:755; }","duration":"208.516074ms","start":"2026-04-02T16:38:30.845464Z","end":"2026-04-02T16:38:31.05398Z","steps":["trace[2124217007] 'agreement among raft nodes before linearized reading' (duration: 207.220739ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:31.054302Z","caller":"traceutil/trace.go:171","msg":"trace[555762320] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:755; }","duration":"208.151917ms","start":"2026-04-02T16:38:30.845522Z","end":"2026-04-02T16:38:31.053674Z","steps":["trace[555762320] 'agreement among raft nodes before linearized reading' (duration: 207.956383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:31.054314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.489279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:38:31.054397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.652991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:833"} {"level":"info","ts":"2026-04-02T16:38:31.054539Z","caller":"traceutil/trace.go:171","msg":"trace[183107666] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:755; }","duration":"227.764873ms","start":"2026-04-02T16:38:30.826702Z","end":"2026-04-02T16:38:31.054467Z","steps":["trace[183107666] 'agreement among raft nodes before linearized reading' (duration: 227.241922ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:31.054766Z","caller":"traceutil/trace.go:171","msg":"trace[157589735] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:755; }","duration":"236.939838ms","start":"2026-04-02T16:38:30.817589Z","end":"2026-04-02T16:38:31.054529Z","steps":["trace[157589735] 'agreement among raft nodes before linearized reading' (duration: 236.308295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:31.055604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.923616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-04-02T16:38:31.055658Z","caller":"traceutil/trace.go:171","msg":"trace[366342820] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:755; }","duration":"229.016659ms","start":"2026-04-02T16:38:30.826624Z","end":"2026-04-02T16:38:31.055641Z","steps":["trace[366342820] 'agreement among raft nodes before linearized reading' (duration: 228.427316ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:32.038008Z","caller":"traceutil/trace.go:171","msg":"trace[1328324937] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"123.647085ms","start":"2026-04-02T16:38:31.91432Z","end":"2026-04-02T16:38:32.037967Z","steps":["trace[1328324937] 'process raft request' (duration: 123.344059ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:32.039844Z","caller":"traceutil/trace.go:171","msg":"trace[219056577] transaction","detail":"{read_only:false; number_of_response:0; response_revision:759; }","duration":"125.339919ms","start":"2026-04-02T16:38:31.914478Z","end":"2026-04-02T16:38:32.039818Z","steps":["trace[219056577] 'process raft request' (duration: 125.256467ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:33.964915Z","caller":"traceutil/trace.go:171","msg":"trace[812246378] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"149.034136ms","start":"2026-04-02T16:38:33.815855Z","end":"2026-04-02T16:38:33.964889Z","steps":["trace[812246378] 'process raft request' (duration: 148.283862ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:34.133848Z","caller":"traceutil/trace.go:171","msg":"trace[9065282] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"101.597767ms","start":"2026-04-02T16:38:34.032232Z","end":"2026-04-02T16:38:34.13383Z","steps":["trace[9065282] 'process raft request' (duration: 101.435604ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:35.86636Z","caller":"traceutil/trace.go:171","msg":"trace[917221752] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"119.728741ms","start":"2026-04-02T16:38:35.746603Z","end":"2026-04-02T16:38:35.866332Z","steps":["trace[917221752] 'process raft request' (duration: 119.589059ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:36.033037Z","caller":"traceutil/trace.go:171","msg":"trace[192317248] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"159.598405ms","start":"2026-04-02T16:38:35.873393Z","end":"2026-04-02T16:38:36.032991Z","steps":["trace[192317248] 'process raft request' (duration: 67.992003ms)","trace[192317248] 'compare' (duration: 91.447779ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:36.035738Z","caller":"traceutil/trace.go:171","msg":"trace[1499876696] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"158.88159ms","start":"2026-04-02T16:38:35.876804Z","end":"2026-04-02T16:38:36.035685Z","steps":["trace[1499876696] 'process raft request' (duration: 158.486032ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:36.035911Z","caller":"traceutil/trace.go:171","msg":"trace[2069611619] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"155.415333ms","start":"2026-04-02T16:38:35.880453Z","end":"2026-04-02T16:38:36.035869Z","steps":["trace[2069611619] 'process raft request' (duration: 155.026376ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:38.683054Z","caller":"traceutil/trace.go:171","msg":"trace[1244342545] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"319.714804ms","start":"2026-04-02T16:38:38.363293Z","end":"2026-04-02T16:38:38.683008Z","steps":["trace[1244342545] 'read index received' (duration: 319.400969ms)","trace[1244342545] 'applied index is now lower than readState.Index' (duration: 312.415µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:38.68315Z","caller":"traceutil/trace.go:171","msg":"trace[1054052974] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"343.212556ms","start":"2026-04-02T16:38:38.339896Z","end":"2026-04-02T16:38:38.683109Z","steps":["trace[1054052974] 'process raft request' (duration: 342.830689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:38.68347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.206813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T16:38:38.683466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.145692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T16:38:38.683518Z","caller":"traceutil/trace.go:171","msg":"trace[1086911963] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:800; }","duration":"169.256914ms","start":"2026-04-02T16:38:38.51425Z","end":"2026-04-02T16:38:38.683507Z","steps":["trace[1086911963] 'agreement among raft nodes before linearized reading' (duration: 169.169522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:38.683506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.339983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:38.683564Z","caller":"traceutil/trace.go:171","msg":"trace[1130512249] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:800; }","duration":"320.308186ms","start":"2026-04-02T16:38:38.363234Z","end":"2026-04-02T16:38:38.683543Z","steps":["trace[1130512249] 'agreement among raft nodes before linearized reading' (duration: 319.989709ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:38.683596Z","caller":"traceutil/trace.go:171","msg":"trace[2004611274] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:800; }","duration":"154.496127ms","start":"2026-04-02T16:38:38.529076Z","end":"2026-04-02T16:38:38.683572Z","steps":["trace[2004611274] 'agreement among raft nodes before linearized reading' (duration: 154.294962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:38.683595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.231761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:38:38.683632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:38.363212Z","time spent":"320.408718ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T16:38:38.683684Z","caller":"traceutil/trace.go:171","msg":"trace[2138796383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"197.376384ms","start":"2026-04-02T16:38:38.486292Z","end":"2026-04-02T16:38:38.683669Z","steps":["trace[2138796383] 'agreement among raft nodes before linearized reading' (duration: 197.21164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:38.683828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:38.339863Z","time spent":"343.373579ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:38:38.684365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.896937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:38:38.68443Z","caller":"traceutil/trace.go:171","msg":"trace[1946160901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:800; }","duration":"320.981788ms","start":"2026-04-02T16:38:38.36343Z","end":"2026-04-02T16:38:38.684412Z","steps":["trace[1946160901] 'agreement among raft nodes before linearized reading' (duration: 319.893357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:38.684632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:38.363418Z","time spent":"321.04324ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T16:38:41.273812Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409463135786734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:38:41.774804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409463135786734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:38:42.275115Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409463135786734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:38:42.55376Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.754956717s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T16:38:42.84267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.51034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:38:42.842793Z","caller":"traceutil/trace.go:171","msg":"trace[2058822165] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"2.069971409s","start":"2026-04-02T16:38:40.772802Z","end":"2026-04-02T16:38:42.842773Z","steps":["trace[2058822165] 'read index received' (duration: 1.781156333s)","trace[2058822165] 'applied index is now lower than readState.Index' (duration: 288.813916ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:42.843208Z","caller":"traceutil/trace.go:171","msg":"trace[229496839] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"2.077504637s","start":"2026-04-02T16:38:40.765668Z","end":"2026-04-02T16:38:42.843172Z","steps":["trace[229496839] 'process raft request' (duration: 1.788386156s)","trace[229496839] 'compare' (duration: 288.043422ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:42.843292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:40.765636Z","time spent":"2.077615949s","remote":"127.0.0.1:39148","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-02T16:38:42.84359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.070790144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:38:42.843629Z","caller":"traceutil/trace.go:171","msg":"trace[334270699] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:818; }","duration":"2.070889196s","start":"2026-04-02T16:38:40.772728Z","end":"2026-04-02T16:38:42.843617Z","steps":["trace[334270699] 'agreement among raft nodes before linearized reading' (duration: 2.070799843s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:42.84366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:40.7727Z","time spent":"2.070951607s","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-02T16:38:42.84395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137173308s","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-02T16:38:42.843985Z","caller":"traceutil/trace.go:171","msg":"trace[492312274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:818; }","duration":"1.13725489s","start":"2026-04-02T16:38:41.70672Z","end":"2026-04-02T16:38:42.843975Z","steps":["trace[492312274] 'agreement among raft nodes before linearized reading' (duration: 1.137125738s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:42.844016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:41.706691Z","time spent":"1.137318711s","remote":"127.0.0.1:39226","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-02T16:38:42.844365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.235419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:38:42.844396Z","caller":"traceutil/trace.go:171","msg":"trace[2104032569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:818; }","duration":"136.30217ms","start":"2026-04-02T16:38:42.708085Z","end":"2026-04-02T16:38:42.844387Z","steps":["trace[2104032569] 'agreement among raft nodes before linearized reading' (duration: 136.198109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:42.844595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.902356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T16:38:42.844621Z","caller":"traceutil/trace.go:171","msg":"trace[1395400572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:818; }","duration":"139.950967ms","start":"2026-04-02T16:38:42.704662Z","end":"2026-04-02T16:38:42.844613Z","steps":["trace[1395400572] 'agreement among raft nodes before linearized reading' (duration: 139.866575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:42.844799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.904803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:38:42.844829Z","caller":"traceutil/trace.go:171","msg":"trace[76707544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:818; }","duration":"146.980285ms","start":"2026-04-02T16:38:42.69784Z","end":"2026-04-02T16:38:42.844821Z","steps":["trace[76707544] 'agreement among raft nodes before linearized reading' (duration: 146.899644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:42.844973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.72257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:42.845097Z","caller":"traceutil/trace.go:171","msg":"trace[1507174397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"357.905184ms","start":"2026-04-02T16:38:42.487165Z","end":"2026-04-02T16:38:42.84507Z","steps":["trace[1507174397] 'agreement among raft nodes before linearized reading' (duration: 357.70248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:42.845181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:42.487138Z","time spent":"358.019895ms","remote":"127.0.0.1:38966","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-02T16:38:42.845319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.650731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T16:38:42.845361Z","caller":"traceutil/trace.go:171","msg":"trace[500667710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:818; }","duration":"733.751233ms","start":"2026-04-02T16:38:42.111596Z","end":"2026-04-02T16:38:42.845347Z","steps":["trace[500667710] 'agreement among raft nodes before linearized reading' (duration: 733.646071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:42.845391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:42.111549Z","time spent":"733.835294ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T16:38:42.845483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.224689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:42.845524Z","caller":"traceutil/trace.go:171","msg":"trace[346778881] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:818; }","duration":"871.357882ms","start":"2026-04-02T16:38:41.974153Z","end":"2026-04-02T16:38:42.845511Z","steps":["trace[346778881] 'agreement among raft nodes before linearized reading' (duration: 871.27752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:42.845557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.418667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:38:42.845633Z","caller":"traceutil/trace.go:171","msg":"trace[284561430] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:818; }","duration":"477.538259ms","start":"2026-04-02T16:38:42.368076Z","end":"2026-04-02T16:38:42.845614Z","steps":["trace[284561430] 'agreement among raft nodes before linearized reading' (duration: 477.395057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:42.845678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:42.368048Z","time spent":"477.620621ms","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-02T16:38:42.845698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.972603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:38:42.845564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:41.974121Z","time spent":"871.432052ms","remote":"127.0.0.1:39208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-04-02T16:38:42.845795Z","caller":"traceutil/trace.go:171","msg":"trace[1223157206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"536.139016ms","start":"2026-04-02T16:38:42.309635Z","end":"2026-04-02T16:38:42.845774Z","steps":["trace[1223157206] 'agreement among raft nodes before linearized reading' (duration: 535.967823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:42.845863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:42.3096Z","time spent":"536.246188ms","remote":"127.0.0.1:38974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T16:38:46.925428Z","caller":"traceutil/trace.go:171","msg":"trace[122225791] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"114.587789ms","start":"2026-04-02T16:38:46.810808Z","end":"2026-04-02T16:38:46.925396Z","steps":["trace[122225791] 'process raft request' (duration: 114.357926ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:47.175558Z","caller":"traceutil/trace.go:171","msg":"trace[889986267] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"243.67857ms","start":"2026-04-02T16:38:46.931837Z","end":"2026-04-02T16:38:47.175516Z","steps":["trace[889986267] 'process raft request' (duration: 240.076877ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:47.175792Z","caller":"traceutil/trace.go:171","msg":"trace[670340271] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"243.780062ms","start":"2026-04-02T16:38:46.931964Z","end":"2026-04-02T16:38:47.175744Z","steps":["trace[670340271] 'process raft request' (duration: 243.330874ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:47.175812Z","caller":"traceutil/trace.go:171","msg":"trace[1888807884] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"242.666292ms","start":"2026-04-02T16:38:46.933103Z","end":"2026-04-02T16:38:47.175769Z","steps":["trace[1888807884] 'process raft request' (duration: 242.598291ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:47.175897Z","caller":"traceutil/trace.go:171","msg":"trace[1033277179] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"243.263703ms","start":"2026-04-02T16:38:46.932618Z","end":"2026-04-02T16:38:47.175882Z","steps":["trace[1033277179] 'process raft request' (duration: 243.040279ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:47.175794Z","caller":"traceutil/trace.go:171","msg":"trace[1294272435] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"243.719161ms","start":"2026-04-02T16:38:46.932034Z","end":"2026-04-02T16:38:47.175754Z","steps":["trace[1294272435] 'process raft request' (duration: 243.575708ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:48.569613Z","caller":"traceutil/trace.go:171","msg":"trace[1240227045] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:906; }","duration":"201.980204ms","start":"2026-04-02T16:38:48.367602Z","end":"2026-04-02T16:38:48.569582Z","steps":["trace[1240227045] 'read index received' (duration: 187.706165ms)","trace[1240227045] 'applied index is now lower than readState.Index' (duration: 14.272979ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:48.569758Z","caller":"traceutil/trace.go:171","msg":"trace[1997136099] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"214.560722ms","start":"2026-04-02T16:38:48.355142Z","end":"2026-04-02T16:38:48.569703Z","steps":["trace[1997136099] 'process raft request' (duration: 214.376329ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:48.569688Z","caller":"traceutil/trace.go:171","msg":"trace[145567655] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"215.197003ms","start":"2026-04-02T16:38:48.354391Z","end":"2026-04-02T16:38:48.569588Z","steps":["trace[145567655] 'process raft request' (duration: 200.865143ms)","trace[145567655] 'compare' (duration: 14.081975ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:48.570116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.466983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-04-02T16:38:48.570287Z","caller":"traceutil/trace.go:171","msg":"trace[404745122] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:875; }","duration":"202.692676ms","start":"2026-04-02T16:38:48.367565Z","end":"2026-04-02T16:38:48.570257Z","steps":["trace[404745122] 'agreement among raft nodes before linearized reading' (duration: 202.168457ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:49.059882Z","caller":"traceutil/trace.go:171","msg":"trace[138484091] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"147.271436ms","start":"2026-04-02T16:38:48.912579Z","end":"2026-04-02T16:38:49.059851Z","steps":["trace[138484091] 'read index received' (duration: 147.029091ms)","trace[138484091] 'applied index is now lower than readState.Index' (duration: 241.295µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:49.059991Z","caller":"traceutil/trace.go:171","msg":"trace[1684596604] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"184.829567ms","start":"2026-04-02T16:38:48.875138Z","end":"2026-04-02T16:38:49.059967Z","steps":["trace[1684596604] 'process raft request' (duration: 184.534551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:49.060133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.50909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:38:49.060256Z","caller":"traceutil/trace.go:171","msg":"trace[1443334739] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:876; }","duration":"147.704084ms","start":"2026-04-02T16:38:48.91253Z","end":"2026-04-02T16:38:49.060234Z","steps":["trace[1443334739] 'agreement among raft nodes before linearized reading' (duration: 147.506031ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:31.846632Z","caller":"traceutil/trace.go:171","msg":"trace[1078550184] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1396; }","duration":"123.973625ms","start":"2026-04-02T16:40:31.722623Z","end":"2026-04-02T16:40:31.846596Z","steps":["trace[1078550184] 'read index received' (duration: 11.212097ms)","trace[1078550184] 'applied index is now lower than readState.Index' (duration: 112.759758ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:31.846779Z","caller":"traceutil/trace.go:171","msg":"trace[1595703123] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"152.754726ms","start":"2026-04-02T16:40:31.693973Z","end":"2026-04-02T16:40:31.846728Z","steps":["trace[1595703123] 'process raft request' (duration: 148.554211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:31.846915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.865014ms","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-02T16:40:31.846963Z","caller":"traceutil/trace.go:171","msg":"trace[375714037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"122.952504ms","start":"2026-04-02T16:40:31.723999Z","end":"2026-04-02T16:40:31.846951Z","steps":["trace[375714037] 'agreement among raft nodes before linearized reading' (duration: 122.772402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:31.846954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.302789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:40:31.847055Z","caller":"traceutil/trace.go:171","msg":"trace[1115082157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1342; }","duration":"124.499421ms","start":"2026-04-02T16:40:31.722533Z","end":"2026-04-02T16:40:31.847032Z","steps":["trace[1115082157] 'agreement among raft nodes before linearized reading' (duration: 124.214058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:31.847129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.214266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:40:31.847165Z","caller":"traceutil/trace.go:171","msg":"trace[1215212147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1342; }","duration":"119.274966ms","start":"2026-04-02T16:40:31.727881Z","end":"2026-04-02T16:40:31.847156Z","steps":["trace[1215212147] 'agreement among raft nodes before linearized reading' (duration: 119.192185ms)"],"step_count":1}