{"level":"warn","ts":"2026-02-10T21:22:55.714322Z","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-10T21:22:55.714409Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43: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.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43: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-10T21:22:55.714474Z","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-10T21:22:55.714482Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-10T21:22:55.7145Z","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-10T21:22:55.715844Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-02-10T21:22:55.715943Z","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.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43: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-10T21:22:55.729308Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.0533ms"} {"level":"info","ts":"2026-02-10T21:22:55.774581Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-02-10T21:22:55.774841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T21:22:55.774971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-02-10T21:22:55.775045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T21:22:55.77513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-02-10T21:22:55.775254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-02-10T21:22:55.804525Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T21:22:55.809812Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T21:22:55.828126Z","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-10T21:22:55.834438Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T21:22:55.834843Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T21:22:55.834952Z","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-10T21:22:55.835045Z","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-10T21:22:55.835092Z","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-10T21:22:55.835712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-02-10T21:22:55.83584Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-10T21:22:55.844707Z","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-10T21:22:55.844773Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-10T21:22:55.844859Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-10T21:22:55.847308Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T21:22:55.847358Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T21:22:56.483181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T21:22:56.483263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T21:22:56.483359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-02-10T21:22:56.483404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-02-10T21:22:56.483424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-10T21:22:56.483454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-02-10T21:22:56.483487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-10T21:22:56.489365Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T21:22:56.489465Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:22:56.489871Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:22:56.490301Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:22:56.490703Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T21:22:56.490756Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T21:22:56.492072Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:22:56.497231Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:22:56.49733Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:22:56.496619Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"info","ts":"2026-02-10T21:22:56.501816Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-10T21:22:59.584477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349458031386582,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T21:22:59.982673Z","caller":"traceutil/trace.go:171","msg":"trace[777106652] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"899.496648ms","start":"2026-02-10T21:22:59.083146Z","end":"2026-02-10T21:22:59.982642Z","steps":["trace[777106652] 'process raft request' (duration: 899.282996ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:22:59.983155Z","caller":"traceutil/trace.go:171","msg":"trace[1934893305] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:80; }","duration":"898.825643ms","start":"2026-02-10T21:22:59.084294Z","end":"2026-02-10T21:22:59.98312Z","steps":["trace[1934893305] 'read index received' (duration: 898.817823ms)","trace[1934893305] 'applied index is now lower than readState.Index' (duration: 5.74µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:22:59.983436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.394977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T21:22:59.983497Z","caller":"traceutil/trace.go:171","msg":"trace[1980152995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:76; }","duration":"787.522768ms","start":"2026-02-10T21:22:59.195962Z","end":"2026-02-10T21:22:59.983485Z","steps":["trace[1980152995] 'agreement among raft nodes before linearized reading' (duration: 787.312376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:22:59.983546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:22:59.195944Z","time spent":"787.589708ms","remote":"127.0.0.1:35820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T21:22:59.98385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:22:59.083125Z","time spent":"899.724509ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T21:22:59.983869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.569438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T21:22:59.984566Z","caller":"traceutil/trace.go:171","msg":"trace[229211194] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:76; }","duration":"900.270443ms","start":"2026-02-10T21:22:59.08428Z","end":"2026-02-10T21:22:59.98455Z","steps":["trace[229211194] 'agreement among raft nodes before linearized reading' (duration: 899.567808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:22:59.984636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:22:59.084273Z","time spent":"900.351994ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"warn","ts":"2026-02-10T21:23:00.491497Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349458031386588,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T21:23:00.806659Z","caller":"traceutil/trace.go:171","msg":"trace[472804722] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:80; }","duration":"815.174025ms","start":"2026-02-10T21:22:59.991452Z","end":"2026-02-10T21:23:00.806626Z","steps":["trace[472804722] 'read index received' (duration: 779.877992ms)","trace[472804722] 'applied index is now lower than readState.Index' (duration: 35.294383ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:23:00.807124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.067279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T21:23:00.80718Z","caller":"traceutil/trace.go:171","msg":"trace[1357176384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"610.22472ms","start":"2026-02-10T21:23:00.196939Z","end":"2026-02-10T21:23:00.807163Z","steps":["trace[1357176384] 'agreement among raft nodes before linearized reading' (duration: 610.071759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:00.807228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:00.19691Z","time spent":"610.30516ms","remote":"127.0.0.1:35820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T21:23:00.807202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.693229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T21:23:00.807331Z","caller":"traceutil/trace.go:171","msg":"trace[25094541] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:77; }","duration":"815.87952ms","start":"2026-02-10T21:22:59.991428Z","end":"2026-02-10T21:23:00.807308Z","steps":["trace[25094541] 'agreement among raft nodes before linearized reading' (duration: 815.543877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:00.807389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:22:59.991413Z","time spent":"815.965781ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"warn","ts":"2026-02-10T21:23:00.807597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.378187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T21:23:00.807652Z","caller":"traceutil/trace.go:171","msg":"trace[537357172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:77; }","duration":"545.512058ms","start":"2026-02-10T21:23:00.262122Z","end":"2026-02-10T21:23:00.807634Z","steps":["trace[537357172] 'agreement among raft nodes before linearized reading' (duration: 545.377087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:00.807694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:00.262114Z","time spent":"545.568008ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T21:23:00.807747Z","caller":"traceutil/trace.go:171","msg":"trace[1127628650] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"817.123449ms","start":"2026-02-10T21:22:59.990607Z","end":"2026-02-10T21:23:00.807731Z","steps":["trace[1127628650] 'process raft request' (duration: 780.711758ms)","trace[1127628650] 'compare' (duration: 34.783179ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:23:00.807952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.006932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:429"} {"level":"warn","ts":"2026-02-10T21:23:00.807959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:22:59.99059Z","time spent":"817.193019ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T21:23:00.808035Z","caller":"traceutil/trace.go:171","msg":"trace[1743923657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:77; }","duration":"546.105393ms","start":"2026-02-10T21:23:00.261879Z","end":"2026-02-10T21:23:00.807984Z","steps":["trace[1743923657] 'agreement among raft nodes before linearized reading' (duration: 546.021972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:00.808085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:00.261833Z","time spent":"546.238993ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":451,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T21:23:00.80818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:00.289646Z","time spent":"518.531187ms","remote":"127.0.0.1:35906","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-10T21:23:23.412837Z","caller":"traceutil/trace.go:171","msg":"trace[1500029636] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"325.842376ms","start":"2026-02-10T21:23:23.086964Z","end":"2026-02-10T21:23:23.412807Z","steps":["trace[1500029636] 'process raft request' (duration: 325.595895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:23.413188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:23.08695Z","time spent":"325.965287ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:23:23.639766Z","caller":"traceutil/trace.go:171","msg":"trace[614540709] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"222.42075ms","start":"2026-02-10T21:23:23.417324Z","end":"2026-02-10T21:23:23.639745Z","steps":["trace[614540709] 'process raft request' (duration: 134.673365ms)","trace[614540709] 'compare' (duration: 87.667654ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:23:26.82856Z","caller":"traceutil/trace.go:171","msg":"trace[1052431637] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"190.070666ms","start":"2026-02-10T21:23:26.638457Z","end":"2026-02-10T21:23:26.828527Z","steps":["trace[1052431637] 'process raft request' (duration: 189.858034ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:26.829263Z","caller":"traceutil/trace.go:171","msg":"trace[2088095537] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:491; }","duration":"128.981928ms","start":"2026-02-10T21:23:26.700267Z","end":"2026-02-10T21:23:26.829249Z","steps":["trace[2088095537] 'read index received' (duration: 128.975398ms)","trace[2088095537] 'applied index is now lower than readState.Index' (duration: 4.67µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:23:26.829359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.074748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:23:26.829395Z","caller":"traceutil/trace.go:171","msg":"trace[1631837716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"129.127489ms","start":"2026-02-10T21:23:26.700254Z","end":"2026-02-10T21:23:26.829381Z","steps":["trace[1631837716] 'agreement among raft nodes before linearized reading' (duration: 129.050008ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:27.063615Z","caller":"traceutil/trace.go:171","msg":"trace[1860836725] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"173.505689ms","start":"2026-02-10T21:23:26.890086Z","end":"2026-02-10T21:23:27.063591Z","steps":["trace[1860836725] 'read index received' (duration: 167.033149ms)","trace[1860836725] 'applied index is now lower than readState.Index' (duration: 6.47121ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:23:27.063798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.68409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:23:27.063833Z","caller":"traceutil/trace.go:171","msg":"trace[1644460438] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:474; }","duration":"173.778641ms","start":"2026-02-10T21:23:26.890044Z","end":"2026-02-10T21:23:27.063823Z","steps":["trace[1644460438] 'agreement among raft nodes before linearized reading' (duration: 173.68972ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:30.750889Z","caller":"traceutil/trace.go:171","msg":"trace[1919738292] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"124.981909ms","start":"2026-02-10T21:23:30.62589Z","end":"2026-02-10T21:23:30.750872Z","steps":["trace[1919738292] 'process raft request' (duration: 124.639077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:32.029217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349458031387538,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T21:23:32.042546Z","caller":"traceutil/trace.go:171","msg":"trace[645274475] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"868.954981ms","start":"2026-02-10T21:23:31.173568Z","end":"2026-02-10T21:23:32.042523Z","steps":["trace[645274475] 'process raft request' (duration: 863.78591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:32.042876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:31.173548Z","time spent":"869.085805ms","remote":"127.0.0.1:35870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T21:23:32.045382Z","caller":"traceutil/trace.go:171","msg":"trace[1159273197] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:567; }","duration":"517.052994ms","start":"2026-02-10T21:23:31.528311Z","end":"2026-02-10T21:23:32.045364Z","steps":["trace[1159273197] 'read index received' (duration: 509.162277ms)","trace[1159273197] 'applied index is now lower than readState.Index' (duration: 7.889947ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:23:32.045861Z","caller":"traceutil/trace.go:171","msg":"trace[1329232281] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"690.290188ms","start":"2026-02-10T21:23:31.355558Z","end":"2026-02-10T21:23:32.045848Z","steps":["trace[1329232281] 'process raft request' (duration: 689.637223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:32.046763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:31.355534Z","time spent":"691.181262ms","remote":"127.0.0.1:35870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T21:23:32.047297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.968889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T21:23:32.04736Z","caller":"traceutil/trace.go:171","msg":"trace[1064271526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:552; }","duration":"519.084492ms","start":"2026-02-10T21:23:31.528263Z","end":"2026-02-10T21:23:32.047348Z","steps":["trace[1064271526] 'agreement among raft nodes before linearized reading' (duration: 518.880205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:32.047387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:31.528243Z","time spent":"519.139244ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T21:23:32.047593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.824593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:23:32.047611Z","caller":"traceutil/trace.go:171","msg":"trace[1168073995] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"493.868703ms","start":"2026-02-10T21:23:31.553735Z","end":"2026-02-10T21:23:32.047604Z","steps":["trace[1168073995] 'agreement among raft nodes before linearized reading' (duration: 493.841482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:32.047624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:31.553719Z","time spent":"493.902035ms","remote":"127.0.0.1:35870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-10T21:23:32.047798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.29176ms","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-10T21:23:32.047813Z","caller":"traceutil/trace.go:171","msg":"trace[1267308909] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"294.33295ms","start":"2026-02-10T21:23:31.753475Z","end":"2026-02-10T21:23:32.047808Z","steps":["trace[1267308909] 'agreement among raft nodes before linearized reading' (duration: 294.311959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:32.047862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.114606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-10T21:23:32.047873Z","caller":"traceutil/trace.go:171","msg":"trace[551168652] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:552; }","duration":"348.160797ms","start":"2026-02-10T21:23:31.699708Z","end":"2026-02-10T21:23:32.047869Z","steps":["trace[551168652] 'agreement among raft nodes before linearized reading' (duration: 348.137506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:32.047886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:31.699688Z","time spent":"348.195187ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"warn","ts":"2026-02-10T21:23:32.047958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.590595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:23:32.04797Z","caller":"traceutil/trace.go:171","msg":"trace[606157869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"350.603285ms","start":"2026-02-10T21:23:31.697362Z","end":"2026-02-10T21:23:32.047966Z","steps":["trace[606157869] 'agreement among raft nodes before linearized reading' (duration: 350.584495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:32.047981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:31.697326Z","time spent":"350.652466ms","remote":"127.0.0.1:35820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T21:23:32.048067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.234278ms","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-10T21:23:32.04808Z","caller":"traceutil/trace.go:171","msg":"trace[1581239276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"368.299738ms","start":"2026-02-10T21:23:31.679775Z","end":"2026-02-10T21:23:32.048075Z","steps":["trace[1581239276] 'agreement among raft nodes before linearized reading' (duration: 368.262837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:32.048093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:31.679756Z","time spent":"368.334039ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-10T21:23:38.545173Z","caller":"traceutil/trace.go:171","msg":"trace[1189269951] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"301.932745ms","start":"2026-02-10T21:23:38.243216Z","end":"2026-02-10T21:23:38.545149Z","steps":["trace[1189269951] 'read index received' (duration: 297.678581ms)","trace[1189269951] 'applied index is now lower than readState.Index' (duration: 4.253204ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:23:38.545421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.188284ms","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-10T21:23:38.545459Z","caller":"traceutil/trace.go:171","msg":"trace[114029556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:607; }","duration":"302.263147ms","start":"2026-02-10T21:23:38.243185Z","end":"2026-02-10T21:23:38.545448Z","steps":["trace[114029556] 'agreement among raft nodes before linearized reading' (duration: 302.061049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:38.545494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:38.24317Z","time spent":"302.315529ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-10T21:23:38.545485Z","caller":"traceutil/trace.go:171","msg":"trace[461515201] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"326.486614ms","start":"2026-02-10T21:23:38.218963Z","end":"2026-02-10T21:23:38.54545Z","steps":["trace[461515201] 'process raft request' (duration: 322.005572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:38.545662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:38.218947Z","time spent":"326.616429ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:23:39.862687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.292275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-10T21:23:39.862746Z","caller":"traceutil/trace.go:171","msg":"trace[166168214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"688.404219ms","start":"2026-02-10T21:23:39.174325Z","end":"2026-02-10T21:23:39.862729Z","steps":["trace[166168214] 'range keys from in-memory index tree' (duration: 688.14151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:39.862775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:39.174302Z","time spent":"688.467451ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-10T21:23:39.964699Z","caller":"traceutil/trace.go:171","msg":"trace[384532393] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"360.73238ms","start":"2026-02-10T21:23:39.603927Z","end":"2026-02-10T21:23:39.964659Z","steps":["trace[384532393] 'process raft request' (duration: 360.542643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:39.96493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:39.60391Z","time spent":"360.891766ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:23:40.136212Z","caller":"traceutil/trace.go:171","msg":"trace[183064523] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"259.355939ms","start":"2026-02-10T21:23:39.876824Z","end":"2026-02-10T21:23:40.13618Z","steps":["trace[183064523] 'process raft request' (duration: 251.033095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:42.16766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470582367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:23:42.167744Z","caller":"traceutil/trace.go:171","msg":"trace[1086504583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"1.470719243s","start":"2026-02-10T21:23:40.697005Z","end":"2026-02-10T21:23:42.167724Z","steps":["trace[1086504583] 'range keys from in-memory index tree' (duration: 1.470449863s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:42.167773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:40.696979Z","time spent":"1.470787965s","remote":"127.0.0.1:35820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T21:23:42.167823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613571752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T21:23:42.167895Z","caller":"traceutil/trace.go:171","msg":"trace[62329958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.613696766s","start":"2026-02-10T21:23:40.55418Z","end":"2026-02-10T21:23:42.167877Z","steps":["trace[62329958] 'range keys from in-memory index tree' (duration: 1.613402656s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:42.167926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:40.554159Z","time spent":"1.613760768s","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T21:23:42.168068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284002243s","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-10T21:23:42.168094Z","caller":"traceutil/trace.go:171","msg":"trace[1323591643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"1.284062895s","start":"2026-02-10T21:23:40.884023Z","end":"2026-02-10T21:23:42.168086Z","steps":["trace[1323591643] 'range keys from in-memory index tree' (duration: 1.283867319s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:42.168113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:40.883999Z","time spent":"1.284109727s","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T21:23:42.168145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.564347822s","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-10T21:23:42.168169Z","caller":"traceutil/trace.go:171","msg":"trace[60640583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.564419205s","start":"2026-02-10T21:23:40.603744Z","end":"2026-02-10T21:23:42.168163Z","steps":["trace[60640583] 'range keys from in-memory index tree' (duration: 1.564240919s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:42.168188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:40.603716Z","time spent":"1.564468296s","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T21:23:42.168354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.497057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-10T21:23:42.168379Z","caller":"traceutil/trace.go:171","msg":"trace[159804109] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:616; }","duration":"467.570119ms","start":"2026-02-10T21:23:41.700801Z","end":"2026-02-10T21:23:42.168371Z","steps":["trace[159804109] 'range keys from in-memory index tree' (duration: 467.393304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:42.168398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:41.700777Z","time spent":"467.616421ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"info","ts":"2026-02-10T21:23:42.462712Z","caller":"traceutil/trace.go:171","msg":"trace[1913283749] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"280.002751ms","start":"2026-02-10T21:23:42.182689Z","end":"2026-02-10T21:23:42.462692Z","steps":["trace[1913283749] 'read index received' (duration: 216.461936ms)","trace[1913283749] 'applied index is now lower than readState.Index' (duration: 63.539975ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:23:42.463094Z","caller":"traceutil/trace.go:171","msg":"trace[1106434710] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"278.511118ms","start":"2026-02-10T21:23:42.184518Z","end":"2026-02-10T21:23:42.463029Z","steps":["trace[1106434710] 'process raft request' (duration: 278.098044ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:42.463294Z","caller":"traceutil/trace.go:171","msg":"trace[1121713942] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"286.116571ms","start":"2026-02-10T21:23:42.177172Z","end":"2026-02-10T21:23:42.463288Z","steps":["trace[1121713942] 'process raft request' (duration: 285.237471ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:42.463401Z","caller":"traceutil/trace.go:171","msg":"trace[2122805523] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"283.785701ms","start":"2026-02-10T21:23:42.179608Z","end":"2026-02-10T21:23:42.463394Z","steps":["trace[2122805523] 'process raft request' (duration: 282.976293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:42.463513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.796408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:23:42.463535Z","caller":"traceutil/trace.go:171","msg":"trace[1231200132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"280.86506ms","start":"2026-02-10T21:23:42.182663Z","end":"2026-02-10T21:23:42.463528Z","steps":["trace[1231200132] 'agreement among raft nodes before linearized reading' (duration: 280.785207ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:42.463651Z","caller":"traceutil/trace.go:171","msg":"trace[1701929731] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"267.000641ms","start":"2026-02-10T21:23:42.196645Z","end":"2026-02-10T21:23:42.463646Z","steps":["trace[1701929731] 'process raft request' (duration: 266.021148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:42.485786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.644455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-10T21:23:42.485855Z","caller":"traceutil/trace.go:171","msg":"trace[10230126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"285.758789ms","start":"2026-02-10T21:23:42.200081Z","end":"2026-02-10T21:23:42.48584Z","steps":["trace[10230126] 'agreement among raft nodes before linearized reading' (duration: 285.533911ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:44.974886Z","caller":"traceutil/trace.go:171","msg":"trace[460121068] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"180.077738ms","start":"2026-02-10T21:23:44.794774Z","end":"2026-02-10T21:23:44.974852Z","steps":["trace[460121068] 'process raft request' (duration: 179.858771ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:45.034182Z","caller":"traceutil/trace.go:171","msg":"trace[1645980719] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"228.986516ms","start":"2026-02-10T21:23:44.805166Z","end":"2026-02-10T21:23:45.034153Z","steps":["trace[1645980719] 'process raft request' (duration: 228.817491ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:45.966088Z","caller":"traceutil/trace.go:171","msg":"trace[1363173677] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"148.850256ms","start":"2026-02-10T21:23:45.817207Z","end":"2026-02-10T21:23:45.966058Z","steps":["trace[1363173677] 'process raft request' (duration: 148.439803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:50.712633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.667805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:23:50.712946Z","caller":"traceutil/trace.go:171","msg":"trace[1922316488] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"510.592512ms","start":"2026-02-10T21:23:50.202315Z","end":"2026-02-10T21:23:50.712908Z","steps":["trace[1922316488] 'process raft request' (duration: 201.328778ms)","trace[1922316488] 'compare' (duration: 308.444568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:23:50.713118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:50.202304Z","time spent":"510.706456ms","remote":"127.0.0.1:32828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1873,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:23:50.714395Z","caller":"traceutil/trace.go:171","msg":"trace[1201570991] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:690; }","duration":"206.385308ms","start":"2026-02-10T21:23:50.507983Z","end":"2026-02-10T21:23:50.714368Z","steps":["trace[1201570991] 'read index received' (duration: 204.321244ms)","trace[1201570991] 'applied index is now lower than readState.Index' (duration: 2.062734ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:23:50.714679Z","caller":"traceutil/trace.go:171","msg":"trace[938226415] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"344.356789ms","start":"2026-02-10T21:23:50.370309Z","end":"2026-02-10T21:23:50.714665Z","steps":["trace[938226415] 'process raft request' (duration: 342.46433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:50.714751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:23:50.370285Z","time spent":"344.424332ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:23:50.715216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.271497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T21:23:50.715307Z","caller":"traceutil/trace.go:171","msg":"trace[536619577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:670; }","duration":"201.373631ms","start":"2026-02-10T21:23:50.513918Z","end":"2026-02-10T21:23:50.715292Z","steps":["trace[536619577] 'agreement among raft nodes before linearized reading' (duration: 201.129033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:50.715583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.594327ms","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-10T21:23:50.717731Z","caller":"traceutil/trace.go:171","msg":"trace[1748663333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:670; }","duration":"209.779026ms","start":"2026-02-10T21:23:50.507939Z","end":"2026-02-10T21:23:50.717718Z","steps":["trace[1748663333] 'agreement among raft nodes before linearized reading' (duration: 207.577876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:50.719948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.456605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1888"} {"level":"warn","ts":"2026-02-10T21:23:50.720551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.470939ms","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-10T21:23:50.72059Z","caller":"traceutil/trace.go:171","msg":"trace[869723286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:670; }","duration":"204.52225ms","start":"2026-02-10T21:23:50.516058Z","end":"2026-02-10T21:23:50.72058Z","steps":["trace[869723286] 'agreement among raft nodes before linearized reading' (duration: 204.394836ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:50.720729Z","caller":"traceutil/trace.go:171","msg":"trace[654948339] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:670; }","duration":"117.580369ms","start":"2026-02-10T21:23:50.602449Z","end":"2026-02-10T21:23:50.72003Z","steps":["trace[654948339] 'agreement among raft nodes before linearized reading' (duration: 117.455445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:50.721303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.493724ms","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-10T21:23:50.727251Z","caller":"traceutil/trace.go:171","msg":"trace[1853568539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:670; }","duration":"194.433621ms","start":"2026-02-10T21:23:50.532801Z","end":"2026-02-10T21:23:50.727234Z","steps":["trace[1853568539] 'agreement among raft nodes before linearized reading' (duration: 188.432232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:50.721564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.245848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T21:23:50.727378Z","caller":"traceutil/trace.go:171","msg":"trace[939723660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:670; }","duration":"208.072212ms","start":"2026-02-10T21:23:50.519298Z","end":"2026-02-10T21:23:50.72737Z","steps":["trace[939723660] 'agreement among raft nodes before linearized reading' (duration: 202.193346ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:50.944697Z","caller":"traceutil/trace.go:171","msg":"trace[389598452] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:693; }","duration":"142.087082ms","start":"2026-02-10T21:23:50.80258Z","end":"2026-02-10T21:23:50.944667Z","steps":["trace[389598452] 'read index received' (duration: 141.74877ms)","trace[389598452] 'applied index is now lower than readState.Index' (duration: 337.592µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:23:50.944962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.142758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2251"} {"level":"info","ts":"2026-02-10T21:23:50.944992Z","caller":"traceutil/trace.go:171","msg":"trace[572581213] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:676; }","duration":"197.230411ms","start":"2026-02-10T21:23:50.747753Z","end":"2026-02-10T21:23:50.944984Z","steps":["trace[572581213] 'agreement among raft nodes before linearized reading' (duration: 197.019334ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:50.94512Z","caller":"traceutil/trace.go:171","msg":"trace[1534930263] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"214.377551ms","start":"2026-02-10T21:23:50.730679Z","end":"2026-02-10T21:23:50.945057Z","steps":["trace[1534930263] 'process raft request' (duration: 213.878425ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:50.945278Z","caller":"traceutil/trace.go:171","msg":"trace[1671824385] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"213.123052ms","start":"2026-02-10T21:23:50.732146Z","end":"2026-02-10T21:23:50.945269Z","steps":["trace[1671824385] 'process raft request' (duration: 212.44622ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:50.945378Z","caller":"traceutil/trace.go:171","msg":"trace[2016996907] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"208.254728ms","start":"2026-02-10T21:23:50.737117Z","end":"2026-02-10T21:23:50.945371Z","steps":["trace[2016996907] 'process raft request' (duration: 207.494874ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:50.945416Z","caller":"traceutil/trace.go:171","msg":"trace[890902331] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"206.827933ms","start":"2026-02-10T21:23:50.738584Z","end":"2026-02-10T21:23:50.945412Z","steps":["trace[890902331] 'process raft request' (duration: 206.059649ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:50.945658Z","caller":"traceutil/trace.go:171","msg":"trace[1287509635] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"216.082175ms","start":"2026-02-10T21:23:50.72957Z","end":"2026-02-10T21:23:50.945652Z","steps":["trace[1287509635] 'process raft request' (duration: 214.836106ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:51.07931Z","caller":"traceutil/trace.go:171","msg":"trace[1127580065] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"124.81678ms","start":"2026-02-10T21:23:50.954462Z","end":"2026-02-10T21:23:51.079279Z","steps":["trace[1127580065] 'process raft request' (duration: 123.288352ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:53.830085Z","caller":"traceutil/trace.go:171","msg":"trace[1617958378] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"116.554166ms","start":"2026-02-10T21:23:53.713521Z","end":"2026-02-10T21:23:53.830075Z","steps":["trace[1617958378] 'process raft request' (duration: 73.943805ms)","trace[1617958378] 'compare' (duration: 42.177967ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:23:53.830056Z","caller":"traceutil/trace.go:171","msg":"trace[885505172] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"116.123903ms","start":"2026-02-10T21:23:53.713903Z","end":"2026-02-10T21:23:53.830027Z","steps":["trace[885505172] 'read index received' (duration: 73.571774ms)","trace[885505172] 'applied index is now lower than readState.Index' (duration: 42.551239ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:23:53.83045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.524035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:23:53.830469Z","caller":"traceutil/trace.go:171","msg":"trace[966227977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"116.673289ms","start":"2026-02-10T21:23:53.713789Z","end":"2026-02-10T21:23:53.830463Z","steps":["trace[966227977] 'agreement among raft nodes before linearized reading' (duration: 116.573226ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:53.832314Z","caller":"traceutil/trace.go:171","msg":"trace[2025361400] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"116.567575ms","start":"2026-02-10T21:23:53.715738Z","end":"2026-02-10T21:23:53.832306Z","steps":["trace[2025361400] 'process raft request' (duration: 116.268846ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:54.720386Z","caller":"traceutil/trace.go:171","msg":"trace[703372242] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"101.945765ms","start":"2026-02-10T21:23:54.618412Z","end":"2026-02-10T21:23:54.720358Z","steps":["trace[703372242] 'process raft request' (duration: 84.429767ms)","trace[703372242] 'compare' (duration: 16.867879ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:23:56.122664Z","caller":"traceutil/trace.go:171","msg":"trace[1118280259] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"147.03176ms","start":"2026-02-10T21:23:55.975617Z","end":"2026-02-10T21:23:56.122649Z","steps":["trace[1118280259] 'process raft request' (duration: 146.947927ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:56.604431Z","caller":"traceutil/trace.go:171","msg":"trace[1834863832] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"106.799762ms","start":"2026-02-10T21:23:56.497609Z","end":"2026-02-10T21:23:56.604409Z","steps":["trace[1834863832] 'process raft request' (duration: 106.666919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:56.855359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.574444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:23:56.85545Z","caller":"traceutil/trace.go:171","msg":"trace[726208177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:715; }","duration":"154.729629ms","start":"2026-02-10T21:23:56.700698Z","end":"2026-02-10T21:23:56.855428Z","steps":["trace[726208177] 'range keys from in-memory index tree' (duration: 154.478291ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:56.856109Z","caller":"traceutil/trace.go:171","msg":"trace[2049698163] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"152.924445ms","start":"2026-02-10T21:23:56.703169Z","end":"2026-02-10T21:23:56.856093Z","steps":["trace[2049698163] 'process raft request' (duration: 99.455816ms)","trace[2049698163] 'compare' (duration: 52.576772ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:23:57.288257Z","caller":"traceutil/trace.go:171","msg":"trace[1211913772] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"119.718936ms","start":"2026-02-10T21:23:57.168514Z","end":"2026-02-10T21:23:57.288233Z","steps":["trace[1211913772] 'process raft request' (duration: 55.80255ms)","trace[1211913772] 'compare' (duration: 63.827984ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:23:57.319328Z","caller":"traceutil/trace.go:171","msg":"trace[1479612959] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"144.796199ms","start":"2026-02-10T21:23:57.174519Z","end":"2026-02-10T21:23:57.319315Z","steps":["trace[1479612959] 'process raft request' (duration: 144.774378ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:57.319619Z","caller":"traceutil/trace.go:171","msg":"trace[2008935777] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"147.724364ms","start":"2026-02-10T21:23:57.171889Z","end":"2026-02-10T21:23:57.319613Z","steps":["trace[2008935777] 'process raft request' (duration: 147.299741ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:23:57.319719Z","caller":"traceutil/trace.go:171","msg":"trace[442544409] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"146.976772ms","start":"2026-02-10T21:23:57.172736Z","end":"2026-02-10T21:23:57.319713Z","steps":["trace[442544409] 'process raft request' (duration: 146.520848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:23:57.579842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.501592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:23:57.580289Z","caller":"traceutil/trace.go:171","msg":"trace[953290280] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"233.621942ms","start":"2026-02-10T21:23:57.346653Z","end":"2026-02-10T21:23:57.580275Z","steps":["trace[953290280] 'process raft request' (duration: 115.035689ms)","trace[953290280] 'compare' (duration: 117.349598ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:24:01.54547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.917236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:24:01.545646Z","caller":"traceutil/trace.go:171","msg":"trace[735711308] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"197.049785ms","start":"2026-02-10T21:24:01.348584Z","end":"2026-02-10T21:24:01.545634Z","steps":["trace[735711308] 'process raft request' (duration: 66.754928ms)","trace[735711308] 'compare' (duration: 129.824424ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:24:01.5458Z","caller":"traceutil/trace.go:171","msg":"trace[246245165] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"194.910665ms","start":"2026-02-10T21:24:01.350884Z","end":"2026-02-10T21:24:01.545795Z","steps":["trace[246245165] 'process raft request' (duration: 194.663838ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:24:01.545911Z","caller":"traceutil/trace.go:171","msg":"trace[815371268] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:801; }","duration":"189.894974ms","start":"2026-02-10T21:24:01.356011Z","end":"2026-02-10T21:24:01.545906Z","steps":["trace[815371268] 'read index received' (duration: 59.33755ms)","trace[815371268] 'applied index is now lower than readState.Index' (duration: 130.556654ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:24:01.545991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.986226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T21:24:01.546005Z","caller":"traceutil/trace.go:171","msg":"trace[1873807080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"190.001157ms","start":"2026-02-10T21:24:01.355998Z","end":"2026-02-10T21:24:01.546Z","steps":["trace[1873807080] 'agreement among raft nodes before linearized reading' (duration: 189.925955ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:24:01.997943Z","caller":"traceutil/trace.go:171","msg":"trace[1037827457] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"171.586801ms","start":"2026-02-10T21:24:01.826325Z","end":"2026-02-10T21:24:01.997911Z","steps":["trace[1037827457] 'process raft request' (duration: 97.359315ms)","trace[1037827457] 'compare' (duration: 74.129324ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:24:03.364309Z","caller":"traceutil/trace.go:171","msg":"trace[1072578719] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"105.280306ms","start":"2026-02-10T21:24:03.259013Z","end":"2026-02-10T21:24:03.364294Z","steps":["trace[1072578719] 'process raft request' (duration: 104.918226ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:24:03.66798Z","caller":"traceutil/trace.go:171","msg":"trace[518753815] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"110.95169ms","start":"2026-02-10T21:24:03.556999Z","end":"2026-02-10T21:24:03.66795Z","steps":["trace[518753815] 'process raft request' (duration: 110.664702ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:24:03.750312Z","caller":"traceutil/trace.go:171","msg":"trace[766541612] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"147.972055ms","start":"2026-02-10T21:24:03.602317Z","end":"2026-02-10T21:24:03.750289Z","steps":["trace[766541612] 'read index received' (duration: 66.283196ms)","trace[766541612] 'applied index is now lower than readState.Index' (duration: 81.687509ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:24:03.750437Z","caller":"traceutil/trace.go:171","msg":"trace[418004659] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"190.082059ms","start":"2026-02-10T21:24:03.560339Z","end":"2026-02-10T21:24:03.750421Z","steps":["trace[418004659] 'process raft request' (duration: 156.651903ms)","trace[418004659] 'compare' (duration: 33.180929ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:24:03.750846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.511919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T21:24:03.75087Z","caller":"traceutil/trace.go:171","msg":"trace[1263737414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"148.583771ms","start":"2026-02-10T21:24:03.602278Z","end":"2026-02-10T21:24:03.750861Z","steps":["trace[1263737414] 'agreement among raft nodes before linearized reading' (duration: 148.464188ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:24:05.805771Z","caller":"traceutil/trace.go:171","msg":"trace[1716243050] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"107.471414ms","start":"2026-02-10T21:24:05.698281Z","end":"2026-02-10T21:24:05.805753Z","steps":["trace[1716243050] 'read index received' (duration: 107.289588ms)","trace[1716243050] 'applied index is now lower than readState.Index' (duration: 181.076µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:24:05.806132Z","caller":"traceutil/trace.go:171","msg":"trace[2082099224] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"127.470711ms","start":"2026-02-10T21:24:05.678649Z","end":"2026-02-10T21:24:05.80612Z","steps":["trace[2082099224] 'process raft request' (duration: 126.982487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:24:05.806394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.097331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:24:05.806611Z","caller":"traceutil/trace.go:171","msg":"trace[1384106379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"108.334998ms","start":"2026-02-10T21:24:05.698263Z","end":"2026-02-10T21:24:05.806598Z","steps":["trace[1384106379] 'agreement among raft nodes before linearized reading' (duration: 108.091651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:24:06.737221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.093856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:24:06.737805Z","caller":"traceutil/trace.go:171","msg":"trace[159924394] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"925.243377ms","start":"2026-02-10T21:24:05.81253Z","end":"2026-02-10T21:24:06.737773Z","steps":["trace[159924394] 'process raft request' (duration: 101.477403ms)","trace[159924394] 'compare' (duration: 822.784119ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:24:06.738071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:24:05.812517Z","time spent":"925.451842ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:24:06.739562Z","caller":"traceutil/trace.go:171","msg":"trace[498922942] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"926.84576ms","start":"2026-02-10T21:24:05.812701Z","end":"2026-02-10T21:24:06.739547Z","steps":["trace[498922942] 'process raft request' (duration: 924.652441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:24:06.739717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:24:05.812694Z","time spent":"926.975784ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:24:06.740042Z","caller":"traceutil/trace.go:171","msg":"trace[982960648] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:817; }","duration":"206.213327ms","start":"2026-02-10T21:24:06.533813Z","end":"2026-02-10T21:24:06.740027Z","steps":["trace[982960648] 'read index received' (duration: 39.551µs)","trace[982960648] 'applied index is now lower than readState.Index' (duration: 206.172416ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:24:06.740263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.440564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T21:24:06.741056Z","caller":"traceutil/trace.go:171","msg":"trace[247641502] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:793; }","duration":"207.277966ms","start":"2026-02-10T21:24:06.533764Z","end":"2026-02-10T21:24:06.741042Z","steps":["trace[247641502] 'agreement among raft nodes before linearized reading' (duration: 206.446944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:24:07.279808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.295559ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-10T21:24:07.502637Z","caller":"traceutil/trace.go:171","msg":"trace[1994400413] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"121.644877ms","start":"2026-02-10T21:24:07.380977Z","end":"2026-02-10T21:24:07.502622Z","steps":["trace[1994400413] 'process raft request' (duration: 121.396541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:24:09.015659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.303632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:999"} {"level":"info","ts":"2026-02-10T21:24:09.015736Z","caller":"traceutil/trace.go:171","msg":"trace[151909869] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:803; }","duration":"154.414055ms","start":"2026-02-10T21:24:08.861305Z","end":"2026-02-10T21:24:09.015719Z","steps":["trace[151909869] 'range keys from in-memory index tree' (duration: 154.197929ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:24:09.611924Z","caller":"traceutil/trace.go:171","msg":"trace[1303486764] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"103.315232ms","start":"2026-02-10T21:24:09.508596Z","end":"2026-02-10T21:24:09.611911Z","steps":["trace[1303486764] 'process raft request' (duration: 102.965933ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:24:21.896533Z","caller":"traceutil/trace.go:171","msg":"trace[595504689] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"140.487079ms","start":"2026-02-10T21:24:21.756018Z","end":"2026-02-10T21:24:21.896505Z","steps":["trace[595504689] 'process raft request' (duration: 88.634871ms)","trace[595504689] 'compare' (duration: 51.745405ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:24:27.095499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.919799ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-10T21:25:13.408977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.202659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-10T21:25:13.410798Z","caller":"traceutil/trace.go:171","msg":"trace[1582774175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"209.061826ms","start":"2026-02-10T21:25:13.201713Z","end":"2026-02-10T21:25:13.410775Z","steps":["trace[1582774175] 'range keys from in-memory index tree' (duration: 207.013685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:13.409006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.553709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:25:13.411257Z","caller":"traceutil/trace.go:171","msg":"trace[502759409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"579.872355ms","start":"2026-02-10T21:25:12.831372Z","end":"2026-02-10T21:25:13.411244Z","steps":["trace[502759409] 'range keys from in-memory index tree' (duration: 577.435188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:13.41167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:12.831351Z","time spent":"580.301461ms","remote":"127.0.0.1:35820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T21:25:13.409655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.801567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T21:25:13.412149Z","caller":"traceutil/trace.go:171","msg":"trace[1745103488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"168.370046ms","start":"2026-02-10T21:25:13.243764Z","end":"2026-02-10T21:25:13.412134Z","steps":["trace[1745103488] 'range keys from in-memory index tree' (duration: 165.578134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:15.209359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.181867ms","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-10T21:25:15.20942Z","caller":"traceutil/trace.go:171","msg":"trace[476679514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1023; }","duration":"964.290198ms","start":"2026-02-10T21:25:14.245117Z","end":"2026-02-10T21:25:15.209407Z","steps":["trace[476679514] 'range keys from in-memory index tree' (duration: 964.041164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:15.209448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:14.245097Z","time spent":"964.344919ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T21:25:15.209743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.510637514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:25:15.209768Z","caller":"traceutil/trace.go:171","msg":"trace[353908069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"1.510708405s","start":"2026-02-10T21:25:13.699054Z","end":"2026-02-10T21:25:15.209763Z","steps":["trace[353908069] 'range keys from in-memory index tree' (duration: 1.510592383s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:15.209785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:13.699039Z","time spent":"1.510741526s","remote":"127.0.0.1:35834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T21:25:15.209973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257885511s","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-10T21:25:15.209996Z","caller":"traceutil/trace.go:171","msg":"trace[240060913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1023; }","duration":"1.257939131s","start":"2026-02-10T21:25:13.952052Z","end":"2026-02-10T21:25:15.209991Z","steps":["trace[240060913] 'range keys from in-memory index tree' (duration: 1.257810619s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:15.210016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:13.952034Z","time spent":"1.257975723s","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T21:25:15.21012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139456642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-10T21:25:15.21014Z","caller":"traceutil/trace.go:171","msg":"trace[1686627693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"1.139501602s","start":"2026-02-10T21:25:14.070634Z","end":"2026-02-10T21:25:15.210136Z","steps":["trace[1686627693] 'range keys from in-memory index tree' (duration: 1.139368421s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:15.210158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:14.070621Z","time spent":"1.139531824s","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-10T21:25:15.330962Z","caller":"traceutil/trace.go:171","msg":"trace[2098725413] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1065; }","duration":"103.816943ms","start":"2026-02-10T21:25:15.227126Z","end":"2026-02-10T21:25:15.330943Z","steps":["trace[2098725413] 'read index received' (duration: 73.73224ms)","trace[2098725413] 'applied index is now lower than readState.Index' (duration: 30.083123ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:25:15.331127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.980065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:25:15.33116Z","caller":"traceutil/trace.go:171","msg":"trace[1638773252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"104.048956ms","start":"2026-02-10T21:25:15.227101Z","end":"2026-02-10T21:25:15.33115Z","steps":["trace[1638773252] 'agreement among raft nodes before linearized reading' (duration: 103.922674ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:25:15.331351Z","caller":"traceutil/trace.go:171","msg":"trace[1210756568] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"106.367902ms","start":"2026-02-10T21:25:15.224965Z","end":"2026-02-10T21:25:15.331333Z","steps":["trace[1210756568] 'process raft request' (duration: 97.59523ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:25:15.3319Z","caller":"traceutil/trace.go:171","msg":"trace[1423866301] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"106.788918ms","start":"2026-02-10T21:25:15.225099Z","end":"2026-02-10T21:25:15.331888Z","steps":["trace[1423866301] 'process raft request' (duration: 105.801503ms)"],"step_count":1}