{"level":"warn","ts":"2026-02-15T14:14:36.229866Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-15T14:14:36.229954Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.218: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://162.253.55.218:2380","--initial-cluster=instance=https://162.253.55.218:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.218:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.218: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-02-15T14:14:36.230028Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-15T14:14:36.230036Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-02-15T14:14:36.230052Z","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-02-15T14:14:36.230767Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"]} {"level":"info","ts":"2026-02-15T14:14:36.231052Z","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":2,"max-cpu-available":2,"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://162.253.55.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.218: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-02-15T14:14:36.235526Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.28606ms"} {"level":"info","ts":"2026-02-15T14:14:36.250654Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bb7d59f4041c8abe","cluster-id":"5330ca29224af3c4"} {"level":"info","ts":"2026-02-15T14:14:36.250761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=()"} {"level":"info","ts":"2026-02-15T14:14:36.250877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 0"} {"level":"info","ts":"2026-02-15T14:14:36.250946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bb7d59f4041c8abe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-15T14:14:36.250977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 1"} {"level":"info","ts":"2026-02-15T14:14:36.251119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"warn","ts":"2026-02-15T14:14:36.2565Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-15T14:14:36.261654Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-15T14:14:36.270441Z","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-02-15T14:14:36.280362Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bb7d59f4041c8abe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-15T14:14:36.311858Z","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-02-15T14:14:36.312042Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bb7d59f4041c8abe","initial-advertise-peer-urls":["https://162.253.55.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-15T14:14:36.312065Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-15T14:14:36.314994Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-02-15T14:14:36.315477Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-02-15T14:14:36.315505Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bb7d59f4041c8abe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-15T14:14:36.315598Z","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-02-15T14:14:36.315618Z","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-02-15T14:14:36.315626Z","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-02-15T14:14:36.318137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"info","ts":"2026-02-15T14:14:36.318228Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","added-peer-id":"bb7d59f4041c8abe","added-peer-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-02-15T14:14:37.252349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe is starting a new election at term 1"} {"level":"info","ts":"2026-02-15T14:14:37.252391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became pre-candidate at term 1"} {"level":"info","ts":"2026-02-15T14:14:37.252414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgPreVoteResp from bb7d59f4041c8abe at term 1"} {"level":"info","ts":"2026-02-15T14:14:37.252425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became candidate at term 2"} {"level":"info","ts":"2026-02-15T14:14:37.252433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgVoteResp from bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-02-15T14:14:37.252442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became leader at term 2"} {"level":"info","ts":"2026-02-15T14:14:37.25245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bb7d59f4041c8abe elected leader bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-02-15T14:14:37.254745Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bb7d59f4041c8abe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.218:2379]}","request-path":"/0/members/bb7d59f4041c8abe/attributes","cluster-id":"5330ca29224af3c4","publish-timeout":"7s"} {"level":"info","ts":"2026-02-15T14:14:37.254794Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-15T14:14:37.255621Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-15T14:14:37.255904Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-15T14:14:37.258982Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.218:2379"} {"level":"info","ts":"2026-02-15T14:14:37.25944Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","cluster-version":"3.5"} {"level":"info","ts":"2026-02-15T14:14:37.259697Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-15T14:14:37.259777Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-15T14:14:37.262064Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-15T14:14:37.262096Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-15T14:14:37.269007Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-15T14:14:49.943727Z","caller":"traceutil/trace.go:171","msg":"trace[273904957] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"249.474066ms","start":"2026-02-15T14:14:49.694231Z","end":"2026-02-15T14:14:49.943706Z","steps":["trace[273904957] 'process raft request' (duration: 249.349783ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:14:55.140873Z","caller":"traceutil/trace.go:171","msg":"trace[1495820634] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"225.134854ms","start":"2026-02-15T14:14:54.915729Z","end":"2026-02-15T14:14:55.140864Z","steps":["trace[1495820634] 'process raft request' (duration: 224.778376ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:14:55.14065Z","caller":"traceutil/trace.go:171","msg":"trace[697210069] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"178.0904ms","start":"2026-02-15T14:14:54.962541Z","end":"2026-02-15T14:14:55.140632Z","steps":["trace[697210069] 'read index received' (duration: 177.895055ms)","trace[697210069] 'applied index is now lower than readState.Index' (duration: 194.675µs)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:14:55.141953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.256948ms","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-02-15T14:14:55.141991Z","caller":"traceutil/trace.go:171","msg":"trace[2012148224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:454; }","duration":"179.471373ms","start":"2026-02-15T14:14:54.962511Z","end":"2026-02-15T14:14:55.141982Z","steps":["trace[2012148224] 'agreement among raft nodes before linearized reading' (duration: 179.261848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:14:55.143027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.255695ms","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-02-15T14:14:55.143102Z","caller":"traceutil/trace.go:171","msg":"trace[1712518758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:454; }","duration":"179.510504ms","start":"2026-02-15T14:14:54.96358Z","end":"2026-02-15T14:14:55.14309Z","steps":["trace[1712518758] 'agreement among raft nodes before linearized reading' (duration: 178.256865ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:08.338642Z","caller":"traceutil/trace.go:171","msg":"trace[660466442] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"274.685989ms","start":"2026-02-15T14:15:08.06394Z","end":"2026-02-15T14:15:08.338626Z","steps":["trace[660466442] 'process raft request' (duration: 274.554445ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:08.447646Z","caller":"traceutil/trace.go:171","msg":"trace[2134971849] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"295.007215ms","start":"2026-02-15T14:15:08.152619Z","end":"2026-02-15T14:15:08.447626Z","steps":["trace[2134971849] 'process raft request' (duration: 294.78948ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:08.447645Z","caller":"traceutil/trace.go:171","msg":"trace[258955669] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:547; }","duration":"105.169108ms","start":"2026-02-15T14:15:08.342453Z","end":"2026-02-15T14:15:08.447622Z","steps":["trace[258955669] 'read index received' (duration: 105.160598ms)","trace[258955669] 'applied index is now lower than readState.Index' (duration: 7.23µs)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:15:08.447991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.517198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-fw45n\" ","response":"range_response_count:1 size:4822"} {"level":"info","ts":"2026-02-15T14:15:08.448053Z","caller":"traceutil/trace.go:171","msg":"trace[1387399530] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-fw45n; range_end:; response_count:1; response_revision:532; }","duration":"105.616389ms","start":"2026-02-15T14:15:08.342424Z","end":"2026-02-15T14:15:08.44804Z","steps":["trace[1387399530] 'agreement among raft nodes before linearized reading' (duration: 105.312071ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:08.461401Z","caller":"traceutil/trace.go:171","msg":"trace[987356461] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"109.704795ms","start":"2026-02-15T14:15:08.351682Z","end":"2026-02-15T14:15:08.461387Z","steps":["trace[987356461] 'process raft request' (duration: 109.632933ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:09.224385Z","caller":"traceutil/trace.go:171","msg":"trace[2116457211] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"271.046453ms","start":"2026-02-15T14:15:08.953323Z","end":"2026-02-15T14:15:09.22437Z","steps":["trace[2116457211] 'process raft request' (duration: 270.91611ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:09.842668Z","caller":"traceutil/trace.go:171","msg":"trace[1681296052] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:563; }","duration":"656.953472ms","start":"2026-02-15T14:15:09.18569Z","end":"2026-02-15T14:15:09.842643Z","steps":["trace[1681296052] 'read index received' (duration: 38.509084ms)","trace[1681296052] 'applied index is now lower than readState.Index' (duration: 618.443368ms)"],"step_count":2} {"level":"info","ts":"2026-02-15T14:15:09.842864Z","caller":"traceutil/trace.go:171","msg":"trace[1705253318] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"687.294535ms","start":"2026-02-15T14:15:09.155559Z","end":"2026-02-15T14:15:09.842854Z","steps":["trace[1705253318] 'process raft request' (duration: 686.558078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:09.843254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:09.155504Z","time spent":"687.387588ms","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-15T14:15:09.853646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.9522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-15T14:15:09.85375Z","caller":"traceutil/trace.go:171","msg":"trace[159559491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"668.021453ms","start":"2026-02-15T14:15:09.185637Z","end":"2026-02-15T14:15:09.853658Z","steps":["trace[159559491] 'agreement among raft nodes before linearized reading' (duration: 667.937301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:09.85377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:09.185624Z","time spent":"668.140645ms","remote":"127.0.0.1:43894","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-02-15T14:15:10.326774Z","caller":"traceutil/trace.go:171","msg":"trace[1211326238] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:565; }","duration":"484.022223ms","start":"2026-02-15T14:15:09.842737Z","end":"2026-02-15T14:15:10.326759Z","steps":["trace[1211326238] 'read index received' (duration: 482.885107ms)","trace[1211326238] 'applied index is now lower than readState.Index' (duration: 1.136666ms)"],"step_count":2} {"level":"info","ts":"2026-02-15T14:15:10.326843Z","caller":"traceutil/trace.go:171","msg":"trace[789892776] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.091656617s","start":"2026-02-15T14:15:09.23518Z","end":"2026-02-15T14:15:10.326837Z","steps":["trace[789892776] 'process raft request' (duration: 1.090429108s)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:10.327195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:09.235143Z","time spent":"1.091717058s","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:15:10.32785Z","caller":"traceutil/trace.go:171","msg":"trace[10264872] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"776.557962ms","start":"2026-02-15T14:15:09.55127Z","end":"2026-02-15T14:15:10.327828Z","steps":["trace[10264872] 'process raft request' (duration: 775.468735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:10.32794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:09.551245Z","time spent":"776.649133ms","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-15T14:15:10.328147Z","caller":"traceutil/trace.go:171","msg":"trace[778100768] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.003521459s","start":"2026-02-15T14:15:09.324619Z","end":"2026-02-15T14:15:10.32814Z","steps":["trace[778100768] 'process raft request' (duration: 1.001655105s)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:10.328185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:09.324601Z","time spent":"1.00355786s","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:15:10.328333Z","caller":"traceutil/trace.go:171","msg":"trace[1215908471] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"939.285911ms","start":"2026-02-15T14:15:09.389041Z","end":"2026-02-15T14:15:10.328327Z","steps":["trace[1215908471] 'process raft request' (duration: 937.612891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:10.328357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:09.38902Z","time spent":"939.324862ms","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-15T14:15:10.329332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.300737ms","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-02-15T14:15:10.329358Z","caller":"traceutil/trace.go:171","msg":"trace[1633919917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:554; }","duration":"685.37229ms","start":"2026-02-15T14:15:09.64398Z","end":"2026-02-15T14:15:10.329352Z","steps":["trace[1633919917] 'agreement among raft nodes before linearized reading' (duration: 684.820957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:10.329382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:09.643962Z","time spent":"685.416551ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-15T14:15:10.329476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.851585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:15:10.329493Z","caller":"traceutil/trace.go:171","msg":"trace[1881112482] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:554; }","duration":"577.912957ms","start":"2026-02-15T14:15:09.751573Z","end":"2026-02-15T14:15:10.329486Z","steps":["trace[1881112482] 'agreement among raft nodes before linearized reading' (duration: 577.874176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:10.329505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:09.751536Z","time spent":"577.966778ms","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-15T14:15:10.329629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03039259s","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-02-15T14:15:10.329643Z","caller":"traceutil/trace.go:171","msg":"trace[1959204862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:554; }","duration":"1.0304266s","start":"2026-02-15T14:15:09.299212Z","end":"2026-02-15T14:15:10.329639Z","steps":["trace[1959204862] 'agreement among raft nodes before linearized reading' (duration: 1.03039657s)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:10.329656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:09.2992Z","time spent":"1.030452931s","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-15T14:15:10.329838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03126263s","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-02-15T14:15:10.329867Z","caller":"traceutil/trace.go:171","msg":"trace[468075777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:554; }","duration":"1.031321701s","start":"2026-02-15T14:15:09.298528Z","end":"2026-02-15T14:15:10.32985Z","steps":["trace[468075777] 'agreement among raft nodes before linearized reading' (duration: 1.031288001s)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:10.3299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:09.298505Z","time spent":"1.031390332s","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-15T14:15:10.337631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.704625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-15T14:15:10.337701Z","caller":"traceutil/trace.go:171","msg":"trace[909207181] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"385.802788ms","start":"2026-02-15T14:15:09.951885Z","end":"2026-02-15T14:15:10.337688Z","steps":["trace[909207181] 'agreement among raft nodes before linearized reading' (duration: 385.646774ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:10.337724Z","caller":"traceutil/trace.go:171","msg":"trace[1772366232] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"477.610381ms","start":"2026-02-15T14:15:09.860104Z","end":"2026-02-15T14:15:10.337714Z","steps":["trace[1772366232] 'process raft request' (duration: 477.331875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:10.337739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:09.951853Z","time spent":"385.878559ms","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-15T14:15:10.337775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:09.860089Z","time spent":"477.661253ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-15T14:15:10.337897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.382709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-15T14:15:10.337928Z","caller":"traceutil/trace.go:171","msg":"trace[1288914322] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"187.451441ms","start":"2026-02-15T14:15:10.15047Z","end":"2026-02-15T14:15:10.337922Z","steps":["trace[1288914322] 'agreement among raft nodes before linearized reading' (duration: 187.38903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:17.008012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.005338ms","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-02-15T14:15:17.008138Z","caller":"traceutil/trace.go:171","msg":"trace[1218622725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"224.182362ms","start":"2026-02-15T14:15:16.783944Z","end":"2026-02-15T14:15:17.008126Z","steps":["trace[1218622725] 'range keys from in-memory index tree' (duration: 223.868275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:18.89825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600166040690705,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-15T14:15:18.952261Z","caller":"traceutil/trace.go:171","msg":"trace[1476018621] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"554.137398ms","start":"2026-02-15T14:15:18.398102Z","end":"2026-02-15T14:15:18.952239Z","steps":["trace[1476018621] 'read index received' (duration: 553.912833ms)","trace[1476018621] 'applied index is now lower than readState.Index' (duration: 223.715µs)"],"step_count":2} {"level":"info","ts":"2026-02-15T14:15:18.95253Z","caller":"traceutil/trace.go:171","msg":"trace[1011831526] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"847.971626ms","start":"2026-02-15T14:15:18.104547Z","end":"2026-02-15T14:15:18.952519Z","steps":["trace[1011831526] 'process raft request' (duration: 847.533196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:18.952622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:18.104522Z","time spent":"848.036778ms","remote":"127.0.0.1:43818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-15T14:15:18.952878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.771893ms","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-02-15T14:15:18.952914Z","caller":"traceutil/trace.go:171","msg":"trace[1513641223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"554.835885ms","start":"2026-02-15T14:15:18.398064Z","end":"2026-02-15T14:15:18.9529Z","steps":["trace[1513641223] 'agreement among raft nodes before linearized reading' (duration: 554.700641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:18.952979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:18.398046Z","time spent":"554.916038ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-15T14:15:18.953502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.571196ms","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-02-15T14:15:18.953522Z","caller":"traceutil/trace.go:171","msg":"trace[492995838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"533.621777ms","start":"2026-02-15T14:15:18.419895Z","end":"2026-02-15T14:15:18.953517Z","steps":["trace[492995838] 'agreement among raft nodes before linearized reading' (duration: 533.543115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:18.953535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:18.419876Z","time spent":"533.656258ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-15T14:15:18.953743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.629495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:15:18.953758Z","caller":"traceutil/trace.go:171","msg":"trace[448959586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"233.665385ms","start":"2026-02-15T14:15:18.720088Z","end":"2026-02-15T14:15:18.953753Z","steps":["trace[448959586] 'agreement among raft nodes before linearized reading' (duration: 233.639165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:18.953914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.894845ms","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-02-15T14:15:18.95393Z","caller":"traceutil/trace.go:171","msg":"trace[826511954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:592; }","duration":"519.922035ms","start":"2026-02-15T14:15:18.434003Z","end":"2026-02-15T14:15:18.953925Z","steps":["trace[826511954] 'agreement among raft nodes before linearized reading' (duration: 519.874404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:18.953973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:18.433997Z","time spent":"519.967446ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-15T14:15:18.954118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.320829ms","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-02-15T14:15:18.954134Z","caller":"traceutil/trace.go:171","msg":"trace[872195334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"524.346529ms","start":"2026-02-15T14:15:18.429782Z","end":"2026-02-15T14:15:18.954129Z","steps":["trace[872195334] 'agreement among raft nodes before linearized reading' (duration: 524.287378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:18.954175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:18.429777Z","time spent":"524.369099ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-15T14:15:30.70249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:30.274183Z","time spent":"428.303734ms","remote":"127.0.0.1:43654","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-15T14:15:30.749436Z","caller":"traceutil/trace.go:171","msg":"trace[389127939] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"142.349881ms","start":"2026-02-15T14:15:30.607071Z","end":"2026-02-15T14:15:30.749421Z","steps":["trace[389127939] 'process raft request' (duration: 142.190708ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:30.893139Z","caller":"traceutil/trace.go:171","msg":"trace[1000997491] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"189.742035ms","start":"2026-02-15T14:15:30.703381Z","end":"2026-02-15T14:15:30.893123Z","steps":["trace[1000997491] 'process raft request' (duration: 185.061544ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:39.361851Z","caller":"traceutil/trace.go:171","msg":"trace[593075841] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"181.184893ms","start":"2026-02-15T14:15:39.180646Z","end":"2026-02-15T14:15:39.36183Z","steps":["trace[593075841] 'process raft request' (duration: 181.016459ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:39.453837Z","caller":"traceutil/trace.go:171","msg":"trace[256890308] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"271.999225ms","start":"2026-02-15T14:15:39.18182Z","end":"2026-02-15T14:15:39.453819Z","steps":["trace[256890308] 'process raft request' (duration: 271.953474ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:39.453937Z","caller":"traceutil/trace.go:171","msg":"trace[716441991] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"271.394881ms","start":"2026-02-15T14:15:39.182523Z","end":"2026-02-15T14:15:39.453918Z","steps":["trace[716441991] 'process raft request' (duration: 271.272379ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:39.454057Z","caller":"traceutil/trace.go:171","msg":"trace[1270998465] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"273.331467ms","start":"2026-02-15T14:15:39.180717Z","end":"2026-02-15T14:15:39.454049Z","steps":["trace[1270998465] 'process raft request' (duration: 267.582052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:40.58736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.553064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-15T14:15:40.587414Z","caller":"traceutil/trace.go:171","msg":"trace[2016269000] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:743; }","duration":"173.645576ms","start":"2026-02-15T14:15:40.413755Z","end":"2026-02-15T14:15:40.587401Z","steps":["trace[2016269000] 'range keys from in-memory index tree' (duration: 173.472402ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:41.354488Z","caller":"traceutil/trace.go:171","msg":"trace[696563827] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"174.821284ms","start":"2026-02-15T14:15:41.179649Z","end":"2026-02-15T14:15:41.35447Z","steps":["trace[696563827] 'read index received' (duration: 174.696861ms)","trace[696563827] 'applied index is now lower than readState.Index' (duration: 123.673µs)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:15:41.354653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.990987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-15T14:15:41.354676Z","caller":"traceutil/trace.go:171","msg":"trace[1829377650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:744; }","duration":"175.060189ms","start":"2026-02-15T14:15:41.179608Z","end":"2026-02-15T14:15:41.354668Z","steps":["trace[1829377650] 'agreement among raft nodes before linearized reading' (duration: 174.931346ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:41.354754Z","caller":"traceutil/trace.go:171","msg":"trace[1360857888] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"354.456311ms","start":"2026-02-15T14:15:41.000278Z","end":"2026-02-15T14:15:41.354734Z","steps":["trace[1360857888] 'process raft request' (duration: 354.077732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:41.354852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:41.000258Z","time spent":"354.540792ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:15:47.573412Z","caller":"traceutil/trace.go:171","msg":"trace[2119312746] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"195.615413ms","start":"2026-02-15T14:15:47.37778Z","end":"2026-02-15T14:15:47.573395Z","steps":["trace[2119312746] 'process raft request' (duration: 195.530821ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:15:47.573671Z","caller":"traceutil/trace.go:171","msg":"trace[1124606393] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:793; }","duration":"193.888552ms","start":"2026-02-15T14:15:47.379777Z","end":"2026-02-15T14:15:47.573665Z","steps":["trace[1124606393] 'read index received' (duration: 193.886082ms)","trace[1124606393] 'applied index is now lower than readState.Index' (duration: 1.86µs)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:15:47.573769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.978524ms","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-02-15T14:15:47.573785Z","caller":"traceutil/trace.go:171","msg":"trace[1126547346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:768; }","duration":"194.014705ms","start":"2026-02-15T14:15:47.379765Z","end":"2026-02-15T14:15:47.57378Z","steps":["trace[1126547346] 'agreement among raft nodes before linearized reading' (duration: 193.946863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:48.137659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.902064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-15T14:15:48.13779Z","caller":"traceutil/trace.go:171","msg":"trace[1674128637] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"559.541395ms","start":"2026-02-15T14:15:47.578212Z","end":"2026-02-15T14:15:48.137753Z","steps":["trace[1674128637] 'process raft request' (duration: 236.303492ms)","trace[1674128637] 'compare' (duration: 321.541993ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:15:48.137837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:47.578198Z","time spent":"559.619097ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:15:48.141028Z","caller":"traceutil/trace.go:171","msg":"trace[1643846263] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:793; }","duration":"423.90368ms","start":"2026-02-15T14:15:47.717114Z","end":"2026-02-15T14:15:48.141018Z","steps":["trace[1643846263] 'read index received' (duration: 97.412792ms)","trace[1643846263] 'applied index is now lower than readState.Index' (duration: 326.490248ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:15:48.141106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.986372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:15:48.141129Z","caller":"traceutil/trace.go:171","msg":"trace[1390984564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"424.031413ms","start":"2026-02-15T14:15:47.71709Z","end":"2026-02-15T14:15:48.141122Z","steps":["trace[1390984564] 'agreement among raft nodes before linearized reading' (duration: 423.968002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:48.141152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:47.717078Z","time spent":"424.069704ms","remote":"127.0.0.1:39724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-15T14:15:48.141435Z","caller":"traceutil/trace.go:171","msg":"trace[651048538] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"562.694628ms","start":"2026-02-15T14:15:47.578732Z","end":"2026-02-15T14:15:48.141427Z","steps":["trace[651048538] 'process raft request' (duration: 562.182607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:48.141484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:47.578725Z","time spent":"562.733789ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:15:48.141563Z","caller":"traceutil/trace.go:171","msg":"trace[822226068] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"558.368247ms","start":"2026-02-15T14:15:47.583189Z","end":"2026-02-15T14:15:48.141557Z","steps":["trace[822226068] 'process raft request' (duration: 557.769614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:48.141599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:47.583174Z","time spent":"558.406679ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:15:48.141671Z","caller":"traceutil/trace.go:171","msg":"trace[119849881] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"554.56999ms","start":"2026-02-15T14:15:47.587095Z","end":"2026-02-15T14:15:48.141665Z","steps":["trace[119849881] 'process raft request' (duration: 553.902384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:15:48.141705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:15:47.587089Z","time spent":"554.5997ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-15T14:16:02.852711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.804693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:16:02.852797Z","caller":"traceutil/trace.go:171","msg":"trace[450964495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"133.920546ms","start":"2026-02-15T14:16:02.718852Z","end":"2026-02-15T14:16:02.852773Z","steps":["trace[450964495] 'range keys from in-memory index tree' (duration: 133.746781ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:04.183858Z","caller":"traceutil/trace.go:171","msg":"trace[513108252] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"276.512971ms","start":"2026-02-15T14:16:03.907282Z","end":"2026-02-15T14:16:04.183795Z","steps":["trace[513108252] 'process raft request' (duration: 276.370658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:04.912012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.074769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-15T14:16:04.912155Z","caller":"traceutil/trace.go:171","msg":"trace[625565139] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:842; }","duration":"707.370111ms","start":"2026-02-15T14:16:04.20477Z","end":"2026-02-15T14:16:04.91214Z","steps":["trace[625565139] 'read index received' (duration: 271.138028ms)","trace[625565139] 'applied index is now lower than readState.Index' (duration: 436.231043ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:04.91236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.573577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-15T14:16:04.912377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.802791ms","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-02-15T14:16:04.912409Z","caller":"traceutil/trace.go:171","msg":"trace[876514110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:816; }","duration":"707.662499ms","start":"2026-02-15T14:16:04.204734Z","end":"2026-02-15T14:16:04.912397Z","steps":["trace[876514110] 'agreement among raft nodes before linearized reading' (duration: 707.505095ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:04.912411Z","caller":"traceutil/trace.go:171","msg":"trace[1205811347] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:816; }","duration":"652.857013ms","start":"2026-02-15T14:16:04.259544Z","end":"2026-02-15T14:16:04.912401Z","steps":["trace[1205811347] 'agreement among raft nodes before linearized reading' (duration: 652.801652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:04.912437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:04.20472Z","time spent":"707.71173ms","remote":"127.0.0.1:43894","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-02-15T14:16:04.912445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:04.259536Z","time spent":"652.900434ms","remote":"127.0.0.1:43874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-02-15T14:16:04.912564Z","caller":"traceutil/trace.go:171","msg":"trace[1887178645] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"714.163449ms","start":"2026-02-15T14:16:04.198392Z","end":"2026-02-15T14:16:04.912556Z","steps":["trace[1887178645] 'process raft request' (duration: 277.466794ms)","trace[1887178645] 'compare' (duration: 435.978277ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:04.912629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:04.198382Z","time spent":"714.19941ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-15T14:16:04.912665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.305342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:16:04.912693Z","caller":"traceutil/trace.go:171","msg":"trace[580632879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"194.360264ms","start":"2026-02-15T14:16:04.718324Z","end":"2026-02-15T14:16:04.912684Z","steps":["trace[580632879] 'agreement among raft nodes before linearized reading' (duration: 194.318473ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:04.91272Z","caller":"traceutil/trace.go:171","msg":"trace[960704310] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"713.490734ms","start":"2026-02-15T14:16:04.199222Z","end":"2026-02-15T14:16:04.912713Z","steps":["trace[960704310] 'process raft request' (duration: 712.876899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:04.912754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:04.199214Z","time spent":"713.520595ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-15T14:16:04.912881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.940906ms","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-02-15T14:16:04.912908Z","caller":"traceutil/trace.go:171","msg":"trace[743693441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:816; }","duration":"643.968147ms","start":"2026-02-15T14:16:04.268932Z","end":"2026-02-15T14:16:04.9129Z","steps":["trace[743693441] 'agreement among raft nodes before linearized reading' (duration: 643.870444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:04.912933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:04.268876Z","time spent":"644.050188ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-15T14:16:05.453666Z","caller":"traceutil/trace.go:171","msg":"trace[285986011] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"533.715088ms","start":"2026-02-15T14:16:04.919924Z","end":"2026-02-15T14:16:05.453639Z","steps":["trace[285986011] 'process raft request' (duration: 494.219115ms)","trace[285986011] 'compare' (duration: 39.194266ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:05.453791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:04.919904Z","time spent":"533.823901ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:16:05.610264Z","caller":"traceutil/trace.go:171","msg":"trace[1408663658] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"690.107674ms","start":"2026-02-15T14:16:04.920136Z","end":"2026-02-15T14:16:05.610243Z","steps":["trace[1408663658] 'process raft request' (duration: 685.472027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:05.612214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:04.920124Z","time spent":"691.086336ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:16:05.636902Z","caller":"traceutil/trace.go:171","msg":"trace[1522606825] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:845; }","duration":"151.141524ms","start":"2026-02-15T14:16:05.485717Z","end":"2026-02-15T14:16:05.636858Z","steps":["trace[1522606825] 'read index received' (duration: 119.840341ms)","trace[1522606825] 'applied index is now lower than readState.Index' (duration: 31.299723ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:05.637044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.300778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-15T14:16:05.637077Z","caller":"traceutil/trace.go:171","msg":"trace[1181124108] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:818; }","duration":"151.38755ms","start":"2026-02-15T14:16:05.48568Z","end":"2026-02-15T14:16:05.637068Z","steps":["trace[1181124108] 'agreement among raft nodes before linearized reading' (duration: 151.283298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:17.344437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.665818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-15T14:16:17.344535Z","caller":"traceutil/trace.go:171","msg":"trace[1146071632] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"279.857617ms","start":"2026-02-15T14:16:17.064662Z","end":"2026-02-15T14:16:17.344519Z","steps":["trace[1146071632] 'read index received' (duration: 19.73µs)","trace[1146071632] 'applied index is now lower than readState.Index' (duration: 279.836897ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:17.344619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.951541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:16:17.344644Z","caller":"traceutil/trace.go:171","msg":"trace[548906366] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:900; }","duration":"279.999112ms","start":"2026-02-15T14:16:17.064637Z","end":"2026-02-15T14:16:17.344636Z","steps":["trace[548906366] 'agreement among raft nodes before linearized reading' (duration: 279.92589ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:17.344909Z","caller":"traceutil/trace.go:171","msg":"trace[1597206989] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"343.719195ms","start":"2026-02-15T14:16:17.001178Z","end":"2026-02-15T14:16:17.344897Z","steps":["trace[1597206989] 'process raft request' (duration: 36.513804ms)","trace[1597206989] 'compare' (duration: 306.572555ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:17.344994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:17.001161Z","time spent":"343.799997ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:16:19.725386Z","caller":"traceutil/trace.go:171","msg":"trace[1763838267] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"179.904769ms","start":"2026-02-15T14:16:19.545463Z","end":"2026-02-15T14:16:19.725368Z","steps":["trace[1763838267] 'process raft request' (duration: 179.636133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:19.995056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.362277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-15T14:16:19.995112Z","caller":"traceutil/trace.go:171","msg":"trace[562909740] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"169.365046ms","start":"2026-02-15T14:16:19.825737Z","end":"2026-02-15T14:16:19.995102Z","steps":["trace[562909740] 'read index received' (duration: 37.861376ms)","trace[562909740] 'applied index is now lower than readState.Index' (duration: 131.50313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:19.995199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.464828ms","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-02-15T14:16:19.995212Z","caller":"traceutil/trace.go:171","msg":"trace[74120820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:907; }","duration":"169.499909ms","start":"2026-02-15T14:16:19.825707Z","end":"2026-02-15T14:16:19.995207Z","steps":["trace[74120820] 'agreement among raft nodes before linearized reading' (duration: 169.420307ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:19.995387Z","caller":"traceutil/trace.go:171","msg":"trace[1729123553] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"262.14351ms","start":"2026-02-15T14:16:19.733237Z","end":"2026-02-15T14:16:19.995381Z","steps":["trace[1729123553] 'process raft request' (duration: 130.411015ms)","trace[1729123553] 'compare' (duration: 131.263394ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:20.643441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.69115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-15T14:16:20.643483Z","caller":"traceutil/trace.go:171","msg":"trace[24360854] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:909; }","duration":"149.761742ms","start":"2026-02-15T14:16:20.493711Z","end":"2026-02-15T14:16:20.643473Z","steps":["trace[24360854] 'range keys from in-memory index tree' (duration: 149.635869ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:22.584202Z","caller":"traceutil/trace.go:171","msg":"trace[1640425816] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"138.284118ms","start":"2026-02-15T14:16:22.4459Z","end":"2026-02-15T14:16:22.584184Z","steps":["trace[1640425816] 'process raft request' (duration: 138.117364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:23.11656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.016778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:16:23.116606Z","caller":"traceutil/trace.go:171","msg":"trace[1855895747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"397.11992ms","start":"2026-02-15T14:16:22.719476Z","end":"2026-02-15T14:16:23.116596Z","steps":["trace[1855895747] 'range keys from in-memory index tree' (duration: 396.831154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:23.116627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:22.719455Z","time spent":"397.167271ms","remote":"127.0.0.1:39724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-15T14:16:23.11683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.402465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:16:23.116845Z","caller":"traceutil/trace.go:171","msg":"trace[1342237962] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:915; }","duration":"197.445605ms","start":"2026-02-15T14:16:22.919394Z","end":"2026-02-15T14:16:23.11684Z","steps":["trace[1342237962] 'count revisions from in-memory index tree' (duration: 197.339353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:23.708919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.163818ms","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-02-15T14:16:23.710242Z","caller":"traceutil/trace.go:171","msg":"trace[592935411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:916; }","duration":"349.52451ms","start":"2026-02-15T14:16:23.360705Z","end":"2026-02-15T14:16:23.71023Z","steps":["trace[592935411] 'range keys from in-memory index tree' (duration: 348.058005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:23.710277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:23.360688Z","time spent":"349.581321ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-15T14:16:23.710075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.282969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:16:23.71039Z","caller":"traceutil/trace.go:171","msg":"trace[851548844] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:916; }","duration":"164.635536ms","start":"2026-02-15T14:16:23.545747Z","end":"2026-02-15T14:16:23.710382Z","steps":["trace[851548844] 'count revisions from in-memory index tree' (duration: 164.215016ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:24.076911Z","caller":"traceutil/trace.go:171","msg":"trace[1914510034] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"351.363513ms","start":"2026-02-15T14:16:23.725533Z","end":"2026-02-15T14:16:24.076897Z","steps":["trace[1914510034] 'read index received' (duration: 351.24047ms)","trace[1914510034] 'applied index is now lower than readState.Index' (duration: 122.523µs)"],"step_count":2} {"level":"info","ts":"2026-02-15T14:16:24.077127Z","caller":"traceutil/trace.go:171","msg":"trace[2010109967] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"355.655812ms","start":"2026-02-15T14:16:23.721464Z","end":"2026-02-15T14:16:24.07712Z","steps":["trace[2010109967] 'process raft request' (duration: 355.349385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:24.077198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:23.721451Z","time spent":"355.690552ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-15T14:16:24.077294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.759192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:16:24.077339Z","caller":"traceutil/trace.go:171","msg":"trace[2038341400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"351.792763ms","start":"2026-02-15T14:16:23.725516Z","end":"2026-02-15T14:16:24.077309Z","steps":["trace[2038341400] 'agreement among raft nodes before linearized reading' (duration: 351.763123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:24.077353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:23.72551Z","time spent":"351.839604ms","remote":"127.0.0.1:39724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-15T14:16:24.077488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.124623ms","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-02-15T14:16:24.077506Z","caller":"traceutil/trace.go:171","msg":"trace[814848103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"334.162525ms","start":"2026-02-15T14:16:23.743339Z","end":"2026-02-15T14:16:24.077501Z","steps":["trace[814848103] 'agreement among raft nodes before linearized reading' (duration: 334.101213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:24.07752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:23.743326Z","time spent":"334.190725ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-15T14:16:24.569367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.522578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-15T14:16:24.569434Z","caller":"traceutil/trace.go:171","msg":"trace[1577872810] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"485.949493ms","start":"2026-02-15T14:16:24.08347Z","end":"2026-02-15T14:16:24.569419Z","steps":["trace[1577872810] 'process raft request' (duration: 178.310652ms)","trace[1577872810] 'compare' (duration: 307.338294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:24.569466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:24.083452Z","time spent":"485.999174ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-15T14:16:24.966187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.213559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-15T14:16:24.966304Z","caller":"traceutil/trace.go:171","msg":"trace[2026690627] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"881.802955ms","start":"2026-02-15T14:16:24.084489Z","end":"2026-02-15T14:16:24.966292Z","steps":["trace[2026690627] 'process raft request' (duration: 588.415512ms)","trace[2026690627] 'compare' (duration: 293.102376ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:24.966346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:24.084482Z","time spent":"881.845746ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:16:24.966447Z","caller":"traceutil/trace.go:171","msg":"trace[1358120382] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:952; }","duration":"245.983636ms","start":"2026-02-15T14:16:24.720458Z","end":"2026-02-15T14:16:24.966441Z","steps":["trace[1358120382] 'read index received' (duration: 35.59µs)","trace[1358120382] 'applied index is now lower than readState.Index' (duration: 245.946866ms)"],"step_count":2} {"level":"info","ts":"2026-02-15T14:16:24.966546Z","caller":"traceutil/trace.go:171","msg":"trace[707495253] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"880.589506ms","start":"2026-02-15T14:16:24.085952Z","end":"2026-02-15T14:16:24.966542Z","steps":["trace[707495253] 'process raft request' (duration: 880.31283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:24.966571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:24.085946Z","time spent":"880.610557ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-15T14:16:24.96672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.748358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:16:24.966797Z","caller":"traceutil/trace.go:171","msg":"trace[252768452] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:920; }","duration":"151.868901ms","start":"2026-02-15T14:16:24.814908Z","end":"2026-02-15T14:16:24.966777Z","steps":["trace[252768452] 'agreement among raft nodes before linearized reading' (duration: 151.738038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:24.966859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.399526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:16:24.96689Z","caller":"traceutil/trace.go:171","msg":"trace[586681852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"246.464517ms","start":"2026-02-15T14:16:24.720419Z","end":"2026-02-15T14:16:24.966883Z","steps":["trace[586681852] 'agreement among raft nodes before linearized reading' (duration: 246.414206ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:30.382458Z","caller":"traceutil/trace.go:171","msg":"trace[1021354163] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"104.835334ms","start":"2026-02-15T14:16:30.277598Z","end":"2026-02-15T14:16:30.382434Z","steps":["trace[1021354163] 'read index received' (duration: 104.709291ms)","trace[1021354163] 'applied index is now lower than readState.Index' (duration: 125.503µs)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:30.382607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.992108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-15T14:16:30.382626Z","caller":"traceutil/trace.go:171","msg":"trace[1845664252] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:933; }","duration":"105.052139ms","start":"2026-02-15T14:16:30.277568Z","end":"2026-02-15T14:16:30.38262Z","steps":["trace[1845664252] 'agreement among raft nodes before linearized reading' (duration: 104.932556ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:30.382872Z","caller":"traceutil/trace.go:171","msg":"trace[1473602011] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"208.252535ms","start":"2026-02-15T14:16:30.174614Z","end":"2026-02-15T14:16:30.382867Z","steps":["trace[1473602011] 'process raft request' (duration: 207.741753ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:30.724516Z","caller":"traceutil/trace.go:171","msg":"trace[1152063861] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"139.254369ms","start":"2026-02-15T14:16:30.585248Z","end":"2026-02-15T14:16:30.724502Z","steps":["trace[1152063861] 'read index received' (duration: 139.159377ms)","trace[1152063861] 'applied index is now lower than readState.Index' (duration: 94.352µs)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:30.724638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.379082ms","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-02-15T14:16:30.724655Z","caller":"traceutil/trace.go:171","msg":"trace[376508573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"139.446233ms","start":"2026-02-15T14:16:30.585204Z","end":"2026-02-15T14:16:30.72465Z","steps":["trace[376508573] 'agreement among raft nodes before linearized reading' (duration: 139.352181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:30.724803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:30.384516Z","time spent":"340.284907ms","remote":"127.0.0.1:43654","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-15T14:16:31.107656Z","caller":"traceutil/trace.go:171","msg":"trace[419264105] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"381.032468ms","start":"2026-02-15T14:16:30.726605Z","end":"2026-02-15T14:16:31.107637Z","steps":["trace[419264105] 'process raft request' (duration: 331.269029ms)","trace[419264105] 'compare' (duration: 49.550064ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:31.107745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:30.726594Z","time spent":"381.103551ms","remote":"127.0.0.1:43654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:16:31.158932Z","caller":"traceutil/trace.go:171","msg":"trace[2041785336] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:969; }","duration":"423.882199ms","start":"2026-02-15T14:16:30.735032Z","end":"2026-02-15T14:16:31.158915Z","steps":["trace[2041785336] 'read index received' (duration: 322.954126ms)","trace[2041785336] 'applied index is now lower than readState.Index' (duration: 100.927573ms)"],"step_count":2} {"level":"info","ts":"2026-02-15T14:16:31.159105Z","caller":"traceutil/trace.go:171","msg":"trace[33613826] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"430.475172ms","start":"2026-02-15T14:16:30.728624Z","end":"2026-02-15T14:16:31.159099Z","steps":["trace[33613826] 'process raft request' (duration: 430.205565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:31.159176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:30.728605Z","time spent":"430.523783ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-15T14:16:31.159283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.248499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-15T14:16:31.1593Z","caller":"traceutil/trace.go:171","msg":"trace[977046904] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:935; }","duration":"424.284319ms","start":"2026-02-15T14:16:30.73501Z","end":"2026-02-15T14:16:31.159295Z","steps":["trace[977046904] 'agreement among raft nodes before linearized reading' (duration: 424.252318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:31.159314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:30.735003Z","time spent":"424.307309ms","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-15T14:16:31.159428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.670051ms","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-02-15T14:16:31.159441Z","caller":"traceutil/trace.go:171","msg":"trace[737332808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:935; }","duration":"175.713082ms","start":"2026-02-15T14:16:30.983725Z","end":"2026-02-15T14:16:31.159438Z","steps":["trace[737332808] 'agreement among raft nodes before linearized reading' (duration: 175.656911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:31.159771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.417622ms","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-02-15T14:16:31.15979Z","caller":"traceutil/trace.go:171","msg":"trace[1286011823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:935; }","duration":"174.461653ms","start":"2026-02-15T14:16:30.985323Z","end":"2026-02-15T14:16:31.159784Z","steps":["trace[1286011823] 'agreement among raft nodes before linearized reading' (duration: 174.420242ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:31.291362Z","caller":"traceutil/trace.go:171","msg":"trace[1668853018] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"126.126795ms","start":"2026-02-15T14:16:31.16522Z","end":"2026-02-15T14:16:31.291347Z","steps":["trace[1668853018] 'read index received' (duration: 76.829895ms)","trace[1668853018] 'applied index is now lower than readState.Index' (duration: 49.29648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:31.291449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.214137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-15T14:16:31.291476Z","caller":"traceutil/trace.go:171","msg":"trace[621838595] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:936; }","duration":"126.261218ms","start":"2026-02-15T14:16:31.165209Z","end":"2026-02-15T14:16:31.291471Z","steps":["trace[621838595] 'agreement among raft nodes before linearized reading' (duration: 126.191607ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:31.291524Z","caller":"traceutil/trace.go:171","msg":"trace[1396402047] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"126.430272ms","start":"2026-02-15T14:16:31.165076Z","end":"2026-02-15T14:16:31.291506Z","steps":["trace[1396402047] 'process raft request' (duration: 77.010369ms)","trace[1396402047] 'compare' (duration: 49.186688ms)"],"step_count":2} {"level":"info","ts":"2026-02-15T14:16:31.296471Z","caller":"traceutil/trace.go:171","msg":"trace[785136414] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"131.186653ms","start":"2026-02-15T14:16:31.165276Z","end":"2026-02-15T14:16:31.296462Z","steps":["trace[785136414] 'process raft request' (duration: 131.118691ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:31.296582Z","caller":"traceutil/trace.go:171","msg":"trace[2104855880] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"129.589815ms","start":"2026-02-15T14:16:31.16697Z","end":"2026-02-15T14:16:31.29656Z","steps":["trace[2104855880] 'process raft request' (duration: 129.471612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:35.216147Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600166040691504,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-15T14:16:35.232946Z","caller":"traceutil/trace.go:171","msg":"trace[1631600440] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"516.835748ms","start":"2026-02-15T14:16:34.716096Z","end":"2026-02-15T14:16:35.232932Z","steps":["trace[1631600440] 'read index received' (duration: 516.747186ms)","trace[1631600440] 'applied index is now lower than readState.Index' (duration: 87.882µs)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:35.233122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.978371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:16:35.233189Z","caller":"traceutil/trace.go:171","msg":"trace[567476989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"517.102203ms","start":"2026-02-15T14:16:34.71607Z","end":"2026-02-15T14:16:35.233172Z","steps":["trace[567476989] 'agreement among raft nodes before linearized reading' (duration: 516.95758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:35.233233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:34.716056Z","time spent":"517.168956ms","remote":"127.0.0.1:39724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-15T14:16:35.23335Z","caller":"traceutil/trace.go:171","msg":"trace[292354350] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"839.66437ms","start":"2026-02-15T14:16:34.393679Z","end":"2026-02-15T14:16:35.233343Z","steps":["trace[292354350] 'process raft request' (duration: 839.157879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:35.233416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:34.393669Z","time spent":"839.698401ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-15T14:16:35.822376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.846694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-15T14:16:35.822493Z","caller":"traceutil/trace.go:171","msg":"trace[1506018187] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:981; }","duration":"522.921287ms","start":"2026-02-15T14:16:35.299561Z","end":"2026-02-15T14:16:35.822482Z","steps":["trace[1506018187] 'read index received' (duration: 45.88265ms)","trace[1506018187] 'applied index is now lower than readState.Index' (duration: 477.037997ms)"],"step_count":2} {"level":"info","ts":"2026-02-15T14:16:35.822645Z","caller":"traceutil/trace.go:171","msg":"trace[427168492] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"584.88141ms","start":"2026-02-15T14:16:35.237754Z","end":"2026-02-15T14:16:35.822635Z","steps":["trace[427168492] 'process raft request' (duration: 107.723569ms)","trace[427168492] 'compare' (duration: 476.643389ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:35.82269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:35.237745Z","time spent":"584.923742ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:16:35.822798Z","caller":"traceutil/trace.go:171","msg":"trace[1986484903] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"535.705265ms","start":"2026-02-15T14:16:35.287087Z","end":"2026-02-15T14:16:35.822792Z","steps":["trace[1986484903] 'process raft request' (duration: 535.359917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:35.822832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:35.287067Z","time spent":"535.749116ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-15T14:16:35.822956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.40099ms","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-02-15T14:16:35.822982Z","caller":"traceutil/trace.go:171","msg":"trace[837109390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:947; }","duration":"523.449851ms","start":"2026-02-15T14:16:35.299526Z","end":"2026-02-15T14:16:35.822976Z","steps":["trace[837109390] 'agreement among raft nodes before linearized reading' (duration: 523.374009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:35.823004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:35.29951Z","time spent":"523.489482ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-15T14:16:35.824004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.451549ms","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-02-15T14:16:35.824043Z","caller":"traceutil/trace.go:171","msg":"trace[341833859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:947; }","duration":"516.477259ms","start":"2026-02-15T14:16:35.307544Z","end":"2026-02-15T14:16:35.824021Z","steps":["trace[341833859] 'agreement among raft nodes before linearized reading' (duration: 516.411708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:35.824063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:35.307537Z","time spent":"516.52112ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-15T14:16:35.824134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.993462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:16:35.824151Z","caller":"traceutil/trace.go:171","msg":"trace[1264359423] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:947; }","duration":"347.020802ms","start":"2026-02-15T14:16:35.477124Z","end":"2026-02-15T14:16:35.824145Z","steps":["trace[1264359423] 'agreement among raft nodes before linearized reading' (duration: 346.993992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:35.824167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:35.47712Z","time spent":"347.042643ms","remote":"127.0.0.1:53138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-15T14:16:35.824275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.613948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-15T14:16:35.824292Z","caller":"traceutil/trace.go:171","msg":"trace[977496883] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:947; }","duration":"354.658669ms","start":"2026-02-15T14:16:35.469629Z","end":"2026-02-15T14:16:35.824287Z","steps":["trace[977496883] 'agreement among raft nodes before linearized reading' (duration: 354.625369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:35.824308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:35.469614Z","time spent":"354.68969ms","remote":"127.0.0.1:44068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-15T14:16:35.824428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.854658ms","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-02-15T14:16:35.824445Z","caller":"traceutil/trace.go:171","msg":"trace[761860005] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:947; }","duration":"516.881718ms","start":"2026-02-15T14:16:35.307558Z","end":"2026-02-15T14:16:35.82444Z","steps":["trace[761860005] 'agreement among raft nodes before linearized reading' (duration: 516.835127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:35.824463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:35.307555Z","time spent":"516.904828ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-15T14:16:36.234948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.213824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-15T14:16:36.235095Z","caller":"traceutil/trace.go:171","msg":"trace[311502961] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"401.634945ms","start":"2026-02-15T14:16:35.833442Z","end":"2026-02-15T14:16:36.235076Z","steps":["trace[311502961] 'process raft request' (duration: 244.242557ms)","trace[311502961] 'compare' (duration: 157.140532ms)"],"step_count":2} {"level":"warn","ts":"2026-02-15T14:16:36.235206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:35.833427Z","time spent":"401.706976ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:16:36.235214Z","caller":"traceutil/trace.go:171","msg":"trace[1369719613] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"401.659846ms","start":"2026-02-15T14:16:35.833541Z","end":"2026-02-15T14:16:36.235201Z","steps":["trace[1369719613] 'process raft request' (duration: 401.468372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:36.235266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:35.833536Z","time spent":"401.702597ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:16:36.235344Z","caller":"traceutil/trace.go:171","msg":"trace[1036531043] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"401.773688ms","start":"2026-02-15T14:16:35.833565Z","end":"2026-02-15T14:16:36.235339Z","steps":["trace[1036531043] 'process raft request' (duration: 401.474321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:36.235374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:16:35.833562Z","time spent":"401.794688ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-15T14:16:38.450007Z","caller":"traceutil/trace.go:171","msg":"trace[1024545360] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"158.844541ms","start":"2026-02-15T14:16:38.291146Z","end":"2026-02-15T14:16:38.44999Z","steps":["trace[1024545360] 'process raft request' (duration: 158.81453ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:38.450221Z","caller":"traceutil/trace.go:171","msg":"trace[386023547] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"206.043072ms","start":"2026-02-15T14:16:38.244172Z","end":"2026-02-15T14:16:38.450215Z","steps":["trace[386023547] 'process raft request' (duration: 205.686784ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:38.450279Z","caller":"traceutil/trace.go:171","msg":"trace[226355906] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"159.219139ms","start":"2026-02-15T14:16:38.291056Z","end":"2026-02-15T14:16:38.450275Z","steps":["trace[226355906] 'process raft request' (duration: 158.880852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:16:38.940192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.578755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-15T14:16:38.940242Z","caller":"traceutil/trace.go:171","msg":"trace[855923997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"222.676957ms","start":"2026-02-15T14:16:38.717553Z","end":"2026-02-15T14:16:38.94023Z","steps":["trace[855923997] 'range keys from in-memory index tree' (duration: 222.508533ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:50.180287Z","caller":"traceutil/trace.go:171","msg":"trace[1978664887] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"205.839479ms","start":"2026-02-15T14:16:49.974424Z","end":"2026-02-15T14:16:50.180263Z","steps":["trace[1978664887] 'process raft request' (duration: 205.326917ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:16:50.35818Z","caller":"traceutil/trace.go:171","msg":"trace[904417976] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"172.310127ms","start":"2026-02-15T14:16:50.185848Z","end":"2026-02-15T14:16:50.358158Z","steps":["trace[904417976] 'process raft request' (duration: 170.468525ms)"],"step_count":1} {"level":"info","ts":"2026-02-15T14:18:49.884808Z","caller":"traceutil/trace.go:171","msg":"trace[727278081] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"109.426483ms","start":"2026-02-15T14:18:49.775353Z","end":"2026-02-15T14:18:49.884779Z","steps":["trace[727278081] 'process raft request' (duration: 48.288133ms)","trace[727278081] 'compare' (duration: 60.778132ms)"],"step_count":2} {"level":"info","ts":"2026-02-15T14:19:59.766301Z","caller":"traceutil/trace.go:171","msg":"trace[73109247] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1924; }","duration":"340.03499ms","start":"2026-02-15T14:19:59.426236Z","end":"2026-02-15T14:19:59.766271Z","steps":["trace[73109247] 'read index received' (duration: 339.869997ms)","trace[73109247] 'applied index is now lower than readState.Index' (duration: 164.353µs)"],"step_count":2} {"level":"info","ts":"2026-02-15T14:19:59.766642Z","caller":"traceutil/trace.go:171","msg":"trace[114159613] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"409.100335ms","start":"2026-02-15T14:19:59.357532Z","end":"2026-02-15T14:19:59.766632Z","steps":["trace[114159613] 'process raft request' (duration: 408.633714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:19:59.766781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:19:59.357515Z","time spent":"409.187147ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-15T14:19:59.767107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.876651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-15T14:19:59.767679Z","caller":"traceutil/trace.go:171","msg":"trace[1541012606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1847; }","duration":"341.501255ms","start":"2026-02-15T14:19:59.426166Z","end":"2026-02-15T14:19:59.767668Z","steps":["trace[1541012606] 'agreement among raft nodes before linearized reading' (duration: 340.85393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-15T14:19:59.767762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-15T14:19:59.426149Z","time spent":"341.601547ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}