{"level":"warn","ts":"2026-02-18T02:42:59.361258Z","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-18T02:42:59.36136Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.170:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.170:2380","--initial-cluster=instance=https://199.19.213.170:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.170:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.170: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-18T02:42:59.361652Z","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-18T02:42:59.361693Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.170:2380"]} {"level":"info","ts":"2026-02-18T02:42:59.361712Z","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-18T02:42:59.362387Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"]} {"level":"info","ts":"2026-02-18T02:42:59.362543Z","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://199.19.213.170:2380"],"listen-peer-urls":["https://199.19.213.170:2380"],"advertise-client-urls":["https://199.19.213.170:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.170: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-18T02:42:59.369447Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.598103ms"} {"level":"info","ts":"2026-02-18T02:42:59.374239Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"80be33c6b0d5a492","cluster-id":"abad33c2eec87dc"} {"level":"info","ts":"2026-02-18T02:42:59.374353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T02:42:59.374376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became follower at term 0"} {"level":"info","ts":"2026-02-18T02:42:59.374384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 80be33c6b0d5a492 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T02:42:59.374393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became follower at term 1"} {"level":"info","ts":"2026-02-18T02:42:59.374454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=(9276909210893132946)"} {"level":"warn","ts":"2026-02-18T02:42:59.381411Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T02:42:59.413592Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T02:42:59.421912Z","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-18T02:42:59.425236Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"80be33c6b0d5a492","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T02:42:59.427095Z","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-18T02:42:59.42732Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"80be33c6b0d5a492","initial-advertise-peer-urls":["https://199.19.213.170:2380"],"listen-peer-urls":["https://199.19.213.170:2380"],"advertise-client-urls":["https://199.19.213.170:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T02:42:59.427343Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T02:42:59.427457Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"80be33c6b0d5a492","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T02:42:59.427513Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.170:2380"} {"level":"info","ts":"2026-02-18T02:42:59.4276Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.170:2380"} {"level":"info","ts":"2026-02-18T02:42:59.427797Z","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-18T02:42:59.427885Z","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-18T02:42:59.427973Z","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-18T02:42:59.42943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=(9276909210893132946)"} {"level":"info","ts":"2026-02-18T02:42:59.430526Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"abad33c2eec87dc","local-member-id":"80be33c6b0d5a492","added-peer-id":"80be33c6b0d5a492","added-peer-peer-urls":["https://199.19.213.170:2380"]} {"level":"info","ts":"2026-02-18T02:43:00.376905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T02:43:00.377054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T02:43:00.377121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 received MsgPreVoteResp from 80be33c6b0d5a492 at term 1"} {"level":"info","ts":"2026-02-18T02:43:00.377178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became candidate at term 2"} {"level":"info","ts":"2026-02-18T02:43:00.377221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 received MsgVoteResp from 80be33c6b0d5a492 at term 2"} {"level":"info","ts":"2026-02-18T02:43:00.377289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became leader at term 2"} {"level":"info","ts":"2026-02-18T02:43:00.377343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 80be33c6b0d5a492 elected leader 80be33c6b0d5a492 at term 2"} {"level":"info","ts":"2026-02-18T02:43:00.380688Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"80be33c6b0d5a492","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.170:2379]}","request-path":"/0/members/80be33c6b0d5a492/attributes","cluster-id":"abad33c2eec87dc","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T02:43:00.382499Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:43:00.382847Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:43:00.383017Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:43:00.384361Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T02:43:00.384377Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T02:43:00.390034Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.170:2379"} {"level":"info","ts":"2026-02-18T02:43:00.390104Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"abad33c2eec87dc","local-member-id":"80be33c6b0d5a492","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:43:00.390149Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:43:00.390163Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:43:00.398673Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-18T02:43:12.730807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11858712767783547258,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:43:12.751498Z","caller":"traceutil/trace.go:171","msg":"trace[205225783] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"523.076591ms","start":"2026-02-18T02:43:12.228399Z","end":"2026-02-18T02:43:12.751475Z","steps":["trace[205225783] 'process raft request' (duration: 522.941259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:12.754295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:12.228379Z","time spent":"523.309073ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:43:12.759958Z","caller":"traceutil/trace.go:171","msg":"trace[2117823967] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:412; }","duration":"529.938489ms","start":"2026-02-18T02:43:12.230009Z","end":"2026-02-18T02:43:12.759947Z","steps":["trace[2117823967] 'read index received' (duration: 524.898701ms)","trace[2117823967] 'applied index is now lower than readState.Index' (duration: 5.039358ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:12.760172Z","caller":"traceutil/trace.go:171","msg":"trace[1745016003] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"349.082901ms","start":"2026-02-18T02:43:12.411082Z","end":"2026-02-18T02:43:12.760164Z","steps":["trace[1745016003] 'process raft request' (duration: 348.815568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:12.760893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:12.411059Z","time spent":"349.761439ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:43:12.760993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.180027ms","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-18T02:43:12.761182Z","caller":"traceutil/trace.go:171","msg":"trace[279792532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:400; }","duration":"500.44659ms","start":"2026-02-18T02:43:12.260726Z","end":"2026-02-18T02:43:12.761173Z","steps":["trace[279792532] 'agreement among raft nodes before linearized reading' (duration: 500.075476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:12.761909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:12.260715Z","time spent":"501.183828ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:43:12.760731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.741139ms","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-18T02:43:12.762335Z","caller":"traceutil/trace.go:171","msg":"trace[1157460646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"532.363607ms","start":"2026-02-18T02:43:12.22995Z","end":"2026-02-18T02:43:12.762314Z","steps":["trace[1157460646] 'agreement among raft nodes before linearized reading' (duration: 530.746628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:12.762412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:12.229938Z","time spent":"532.465198ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T02:43:16.889426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.662026ms","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-18T02:43:16.889498Z","caller":"traceutil/trace.go:171","msg":"trace[304760225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"100.762757ms","start":"2026-02-18T02:43:16.788718Z","end":"2026-02-18T02:43:16.889481Z","steps":["trace[304760225] 'agreement among raft nodes before linearized reading' (duration: 100.600605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:16.889705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.753587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3788"} {"level":"info","ts":"2026-02-18T02:43:16.889734Z","caller":"traceutil/trace.go:171","msg":"trace[943460873] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:426; }","duration":"100.829427ms","start":"2026-02-18T02:43:16.788895Z","end":"2026-02-18T02:43:16.889725Z","steps":["trace[943460873] 'agreement among raft nodes before linearized reading' (duration: 100.767967ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:16.889759Z","caller":"traceutil/trace.go:171","msg":"trace[1987864350] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"100.958369ms","start":"2026-02-18T02:43:16.788782Z","end":"2026-02-18T02:43:16.88974Z","steps":["trace[1987864350] 'process raft request' (duration: 61.378943ms)","trace[1987864350] 'compare' (duration: 39.03674ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:16.889873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.0291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-7lh52\" ","response":"range_response_count:1 size:3994"} {"level":"info","ts":"2026-02-18T02:43:16.889904Z","caller":"traceutil/trace.go:171","msg":"trace[1464206435] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-7lh52; range_end:; response_count:1; response_revision:426; }","duration":"101.06296ms","start":"2026-02-18T02:43:16.788831Z","end":"2026-02-18T02:43:16.889894Z","steps":["trace[1464206435] 'agreement among raft nodes before linearized reading' (duration: 101.01402ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:37.085849Z","caller":"traceutil/trace.go:171","msg":"trace[173059115] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"404.832483ms","start":"2026-02-18T02:43:36.680998Z","end":"2026-02-18T02:43:37.08583Z","steps":["trace[173059115] 'read index received' (duration: 404.681302ms)","trace[173059115] 'applied index is now lower than readState.Index' (duration: 150.421µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:37.085945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.930454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:43:37.085971Z","caller":"traceutil/trace.go:171","msg":"trace[855311165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"404.996235ms","start":"2026-02-18T02:43:36.680965Z","end":"2026-02-18T02:43:37.085962Z","steps":["trace[855311165] 'agreement among raft nodes before linearized reading' (duration: 404.942524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:37.086006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:36.680947Z","time spent":"405.048255ms","remote":"127.0.0.1:48836","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-18T02:43:37.086203Z","caller":"traceutil/trace.go:171","msg":"trace[980254194] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"645.323744ms","start":"2026-02-18T02:43:36.440871Z","end":"2026-02-18T02:43:37.086194Z","steps":["trace[980254194] 'process raft request' (duration: 644.828978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:37.086262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:36.440857Z","time spent":"645.365384ms","remote":"127.0.0.1:49038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:43:37.365055Z","caller":"traceutil/trace.go:171","msg":"trace[1422031329] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"229.896492ms","start":"2026-02-18T02:43:37.135142Z","end":"2026-02-18T02:43:37.365039Z","steps":["trace[1422031329] 'read index received' (duration: 229.820321ms)","trace[1422031329] 'applied index is now lower than readState.Index' (duration: 75.471µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:37.365144Z","caller":"traceutil/trace.go:171","msg":"trace[1528481130] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"240.0978ms","start":"2026-02-18T02:43:37.125041Z","end":"2026-02-18T02:43:37.365139Z","steps":["trace[1528481130] 'process raft request' (duration: 239.897418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:37.365291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.137234ms","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-18T02:43:37.365307Z","caller":"traceutil/trace.go:171","msg":"trace[701229543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"230.189695ms","start":"2026-02-18T02:43:37.135112Z","end":"2026-02-18T02:43:37.365302Z","steps":["trace[701229543] 'agreement among raft nodes before linearized reading' (duration: 230.109964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:37.365412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.610847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T02:43:37.365425Z","caller":"traceutil/trace.go:171","msg":"trace[2085185414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"226.636508ms","start":"2026-02-18T02:43:37.138785Z","end":"2026-02-18T02:43:37.365422Z","steps":["trace[2085185414] 'agreement among raft nodes before linearized reading' (duration: 226.568227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:37.365487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.953832ms","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-18T02:43:37.3655Z","caller":"traceutil/trace.go:171","msg":"trace[814605189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"189.988762ms","start":"2026-02-18T02:43:37.175506Z","end":"2026-02-18T02:43:37.365494Z","steps":["trace[814605189] 'agreement among raft nodes before linearized reading' (duration: 189.950801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:38.181967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11858712767783547571,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:43:38.682495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11858712767783547571,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:43:38.723134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279214189s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:43:38.723209Z","caller":"traceutil/trace.go:171","msg":"trace[1837264640] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.351205197s","start":"2026-02-18T02:43:37.371993Z","end":"2026-02-18T02:43:38.723198Z","steps":["trace[1837264640] 'process raft request' (duration: 71.778186ms)","trace[1837264640] 'compare' (duration: 1.278664084s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:38.723243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:37.371978Z","time spent":"1.351248438s","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:43:38.796197Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.352406219s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T02:43:38.79654Z","caller":"traceutil/trace.go:171","msg":"trace[2099105172] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.423625179s","start":"2026-02-18T02:43:37.372892Z","end":"2026-02-18T02:43:38.796517Z","steps":["trace[2099105172] 'process raft request' (duration: 1.423568268s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:38.796688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:37.372885Z","time spent":"1.42372219s","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:43:38.796714Z","caller":"traceutil/trace.go:171","msg":"trace[1567084323] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.424501348s","start":"2026-02-18T02:43:37.372198Z","end":"2026-02-18T02:43:38.7967Z","steps":["trace[1567084323] 'process raft request' (duration: 1.424141424s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:38.796791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:37.372178Z","time spent":"1.424560608s","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:43:38.921208Z","caller":"traceutil/trace.go:171","msg":"trace[1348628014] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"1.239495457s","start":"2026-02-18T02:43:37.681687Z","end":"2026-02-18T02:43:38.921183Z","steps":["trace[1348628014] 'read index received' (duration: 1.114859526s)","trace[1348628014] 'applied index is now lower than readState.Index' (duration: 124.634491ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:38.921305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239597708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:43:38.921352Z","caller":"traceutil/trace.go:171","msg":"trace[1666586952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"1.239682568s","start":"2026-02-18T02:43:37.681658Z","end":"2026-02-18T02:43:38.92134Z","steps":["trace[1666586952] 'agreement among raft nodes before linearized reading' (duration: 1.239588997s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:38.921385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:37.681642Z","time spent":"1.239738589s","remote":"127.0.0.1:48820","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-18T02:43:40.993166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.017188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:43:40.993226Z","caller":"traceutil/trace.go:171","msg":"trace[1854601165] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:571; }","duration":"324.11495ms","start":"2026-02-18T02:43:40.669097Z","end":"2026-02-18T02:43:40.993212Z","steps":["trace[1854601165] 'range keys from in-memory index tree' (duration: 323.989317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:40.99322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.124983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T02:43:40.993258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:40.669094Z","time spent":"324.157093ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-02-18T02:43:40.99327Z","caller":"traceutil/trace.go:171","msg":"trace[969598225] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:571; }","duration":"324.210645ms","start":"2026-02-18T02:43:40.669047Z","end":"2026-02-18T02:43:40.993258Z","steps":["trace[969598225] 'range keys from in-memory index tree' (duration: 324.028318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:40.993299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:40.669042Z","time spent":"324.252636ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-18T02:43:40.993389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.245415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:43:40.993444Z","caller":"traceutil/trace.go:171","msg":"trace[1839670960] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:571; }","duration":"324.271425ms","start":"2026-02-18T02:43:40.669131Z","end":"2026-02-18T02:43:40.993403Z","steps":["trace[1839670960] 'range keys from in-memory index tree' (duration: 324.226065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:40.99346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:40.669129Z","time spent":"324.325758ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T02:43:40.99351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.691066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:43:40.993524Z","caller":"traceutil/trace.go:171","msg":"trace[39358162] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:571; }","duration":"336.709287ms","start":"2026-02-18T02:43:40.65681Z","end":"2026-02-18T02:43:40.993519Z","steps":["trace[39358162] 'range keys from in-memory index tree' (duration: 336.604133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:40.993536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:40.656767Z","time spent":"336.766058ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-18T02:43:40.99357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.704756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:43:40.99358Z","caller":"traceutil/trace.go:171","msg":"trace[377195874] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:571; }","duration":"336.723326ms","start":"2026-02-18T02:43:40.656853Z","end":"2026-02-18T02:43:40.993576Z","steps":["trace[377195874] 'range keys from in-memory index tree' (duration: 336.684715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:40.993591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:40.656851Z","time spent":"336.737757ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-02-18T02:43:40.993659Z","caller":"traceutil/trace.go:171","msg":"trace[992540240] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"310.655331ms","start":"2026-02-18T02:43:40.682997Z","end":"2026-02-18T02:43:40.993653Z","steps":["trace[992540240] 'read index received' (duration: 310.030052ms)","trace[992540240] 'applied index is now lower than readState.Index' (duration: 624.789µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:40.993821Z","caller":"traceutil/trace.go:171","msg":"trace[454297628] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"324.332138ms","start":"2026-02-18T02:43:40.669484Z","end":"2026-02-18T02:43:40.993816Z","steps":["trace[454297628] 'process raft request' (duration: 323.469672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:40.993842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.838406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:43:40.993867Z","caller":"traceutil/trace.go:171","msg":"trace[1797569230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"310.900928ms","start":"2026-02-18T02:43:40.682959Z","end":"2026-02-18T02:43:40.99386Z","steps":["trace[1797569230] 'agreement among raft nodes before linearized reading' (duration: 310.856417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:40.993869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:40.669453Z","time spent":"324.380479ms","remote":"127.0.0.1:49128","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-18T02:43:40.993889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:40.682945Z","time spent":"310.940419ms","remote":"127.0.0.1:48836","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-18T02:43:41.001542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.221849ms","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-18T02:43:41.001609Z","caller":"traceutil/trace.go:171","msg":"trace[1440576378] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:573; }","duration":"315.299491ms","start":"2026-02-18T02:43:40.686301Z","end":"2026-02-18T02:43:41.0016Z","steps":["trace[1440576378] 'agreement among raft nodes before linearized reading' (duration: 315.183568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:41.00164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:40.686296Z","time spent":"315.337842ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T02:43:41.001772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.687821ms","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-18T02:43:41.001807Z","caller":"traceutil/trace.go:171","msg":"trace[815027488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"200.745222ms","start":"2026-02-18T02:43:40.801056Z","end":"2026-02-18T02:43:41.001801Z","steps":["trace[815027488] 'agreement among raft nodes before linearized reading' (duration: 200.648699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:41.001633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.34192ms","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-18T02:43:41.001891Z","caller":"traceutil/trace.go:171","msg":"trace[1192918640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"199.644079ms","start":"2026-02-18T02:43:40.802239Z","end":"2026-02-18T02:43:41.001883Z","steps":["trace[1192918640] 'agreement among raft nodes before linearized reading' (duration: 199.254737ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:41.001768Z","caller":"traceutil/trace.go:171","msg":"trace[2140613637] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"170.708255ms","start":"2026-02-18T02:43:40.831048Z","end":"2026-02-18T02:43:41.001757Z","steps":["trace[2140613637] 'process raft request' (duration: 170.371405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:41.002029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.540567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T02:43:41.002054Z","caller":"traceutil/trace.go:171","msg":"trace[1315590794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"274.593679ms","start":"2026-02-18T02:43:40.727453Z","end":"2026-02-18T02:43:41.002047Z","steps":["trace[1315590794] 'agreement among raft nodes before linearized reading' (duration: 274.528917ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:41.949069Z","caller":"traceutil/trace.go:171","msg":"trace[383530013] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"116.940378ms","start":"2026-02-18T02:43:41.832113Z","end":"2026-02-18T02:43:41.949054Z","steps":["trace[383530013] 'process raft request' (duration: 116.856875ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:42.103441Z","caller":"traceutil/trace.go:171","msg":"trace[1349072910] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"150.967381ms","start":"2026-02-18T02:43:41.952456Z","end":"2026-02-18T02:43:42.103423Z","steps":["trace[1349072910] 'process raft request' (duration: 75.365475ms)","trace[1349072910] 'compare' (duration: 75.532133ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:42.161498Z","caller":"traceutil/trace.go:171","msg":"trace[346827298] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"130.239984ms","start":"2026-02-18T02:43:42.031234Z","end":"2026-02-18T02:43:42.161474Z","steps":["trace[346827298] 'process raft request' (duration: 130.092789ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:42.347981Z","caller":"traceutil/trace.go:171","msg":"trace[1208058390] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"181.055208ms","start":"2026-02-18T02:43:42.166908Z","end":"2026-02-18T02:43:42.347963Z","steps":["trace[1208058390] 'process raft request' (duration: 116.484743ms)","trace[1208058390] 'compare' (duration: 64.357029ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:42.348313Z","caller":"traceutil/trace.go:171","msg":"trace[647949439] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"116.290986ms","start":"2026-02-18T02:43:42.232017Z","end":"2026-02-18T02:43:42.348308Z","steps":["trace[647949439] 'process raft request' (duration: 116.264146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:42.668544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.998716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:43:42.668645Z","caller":"traceutil/trace.go:171","msg":"trace[944766681] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"305.279551ms","start":"2026-02-18T02:43:42.363348Z","end":"2026-02-18T02:43:42.668628Z","steps":["trace[944766681] 'process raft request' (duration: 146.12317ms)","trace[944766681] 'compare' (duration: 158.870103ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:42.668739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:42.363336Z","time spent":"305.360434ms","remote":"127.0.0.1:48870","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-18T02:43:42.732839Z","caller":"traceutil/trace.go:171","msg":"trace[242041848] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"275.181258ms","start":"2026-02-18T02:43:42.457644Z","end":"2026-02-18T02:43:42.732825Z","steps":["trace[242041848] 'read index received' (duration: 51.837723ms)","trace[242041848] 'applied index is now lower than readState.Index' (duration: 223.342905ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:42.732996Z","caller":"traceutil/trace.go:171","msg":"trace[1929682153] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"300.59881ms","start":"2026-02-18T02:43:42.432391Z","end":"2026-02-18T02:43:42.73299Z","steps":["trace[1929682153] 'process raft request' (duration: 300.340333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:42.733078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:42.432375Z","time spent":"300.639522ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:43:42.733178Z","caller":"traceutil/trace.go:171","msg":"trace[2081605507] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"274.021734ms","start":"2026-02-18T02:43:42.459151Z","end":"2026-02-18T02:43:42.733173Z","steps":["trace[2081605507] 'process raft request' (duration: 273.645463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:42.733317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.668403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ltdvp\" ","response":"range_response_count:1 size:17415"} {"level":"info","ts":"2026-02-18T02:43:42.733338Z","caller":"traceutil/trace.go:171","msg":"trace[1667848648] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ltdvp; range_end:; response_count:1; response_revision:607; }","duration":"275.693733ms","start":"2026-02-18T02:43:42.457639Z","end":"2026-02-18T02:43:42.733333Z","steps":["trace[1667848648] 'agreement among raft nodes before linearized reading' (duration: 275.600761ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:42.935852Z","caller":"traceutil/trace.go:171","msg":"trace[519108282] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"300.100075ms","start":"2026-02-18T02:43:42.635733Z","end":"2026-02-18T02:43:42.935833Z","steps":["trace[519108282] 'process raft request' (duration: 244.101527ms)","trace[519108282] 'compare' (duration: 55.73708ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:42.936133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:42.635716Z","time spent":"300.323282ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:43:42.945703Z","caller":"traceutil/trace.go:171","msg":"trace[1041383790] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"191.265143ms","start":"2026-02-18T02:43:42.754422Z","end":"2026-02-18T02:43:42.945687Z","steps":["trace[1041383790] 'process raft request' (duration: 189.694377ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:42.946963Z","caller":"traceutil/trace.go:171","msg":"trace[30735172] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"186.894493ms","start":"2026-02-18T02:43:42.76006Z","end":"2026-02-18T02:43:42.946955Z","steps":["trace[30735172] 'process raft request' (duration: 184.111489ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:42.947437Z","caller":"traceutil/trace.go:171","msg":"trace[1229086725] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"243.156979ms","start":"2026-02-18T02:43:42.704272Z","end":"2026-02-18T02:43:42.947429Z","steps":["trace[1229086725] 'process raft request' (duration: 231.460488ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:42.949263Z","caller":"traceutil/trace.go:171","msg":"trace[865119748] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"202.903093ms","start":"2026-02-18T02:43:42.732884Z","end":"2026-02-18T02:43:42.935787Z","steps":["trace[865119748] 'read index received' (duration: 147.023278ms)","trace[865119748] 'applied index is now lower than readState.Index' (duration: 55.878975ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:42.949439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.56478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:43:42.949531Z","caller":"traceutil/trace.go:171","msg":"trace[346212290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"265.700905ms","start":"2026-02-18T02:43:42.683824Z","end":"2026-02-18T02:43:42.949525Z","steps":["trace[346212290] 'agreement among raft nodes before linearized reading' (duration: 265.55983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.295573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.454069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:43:43.295704Z","caller":"traceutil/trace.go:171","msg":"trace[745646181] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:632; }","duration":"286.653172ms","start":"2026-02-18T02:43:43.00904Z","end":"2026-02-18T02:43:43.295693Z","steps":["trace[745646181] 'read index received' (duration: 74.024117ms)","trace[745646181] 'applied index is now lower than readState.Index' (duration: 212.628505ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:43.295876Z","caller":"traceutil/trace.go:171","msg":"trace[1090022309] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"319.321691ms","start":"2026-02-18T02:43:42.976546Z","end":"2026-02-18T02:43:43.295867Z","steps":["trace[1090022309] 'process raft request' (duration: 106.501341ms)","trace[1090022309] 'compare' (duration: 212.239273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:43.295918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:42.976529Z","time spent":"319.366182ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:43:43.296033Z","caller":"traceutil/trace.go:171","msg":"trace[492456698] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"293.564169ms","start":"2026-02-18T02:43:43.002463Z","end":"2026-02-18T02:43:43.296027Z","steps":["trace[492456698] 'process raft request' (duration: 293.185478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.296168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.124886ms","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-18T02:43:43.296187Z","caller":"traceutil/trace.go:171","msg":"trace[268861912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:614; }","duration":"287.163867ms","start":"2026-02-18T02:43:43.009017Z","end":"2026-02-18T02:43:43.296181Z","steps":["trace[268861912] 'agreement among raft nodes before linearized reading' (duration: 287.087035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.92052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.218834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:43:43.92086Z","caller":"traceutil/trace.go:171","msg":"trace[5559508] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"556.414383ms","start":"2026-02-18T02:43:43.364432Z","end":"2026-02-18T02:43:43.920847Z","steps":["trace[5559508] 'process raft request' (duration: 556.291999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.920913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.364416Z","time spent":"556.467125ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:43:43.921032Z","caller":"traceutil/trace.go:171","msg":"trace[88181571] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"658.521016ms","start":"2026-02-18T02:43:43.262503Z","end":"2026-02-18T02:43:43.921024Z","steps":["trace[88181571] 'process raft request' (duration: 152.743516ms)","trace[88181571] 'compare' (duration: 504.964616ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:43.921067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.262485Z","time spent":"658.566187ms","remote":"127.0.0.1:48902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:43:43.921174Z","caller":"traceutil/trace.go:171","msg":"trace[1491345370] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"625.425553ms","start":"2026-02-18T02:43:43.295724Z","end":"2026-02-18T02:43:43.921149Z","steps":["trace[1491345370] 'read index received' (duration: 119.697034ms)","trace[1491345370] 'applied index is now lower than readState.Index' (duration: 505.727439ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:43.92138Z","caller":"traceutil/trace.go:171","msg":"trace[982557326] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"614.180649ms","start":"2026-02-18T02:43:43.307176Z","end":"2026-02-18T02:43:43.921357Z","steps":["trace[982557326] 'process raft request' (duration: 613.433147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.921422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.307161Z","time spent":"614.237521ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:43:43.921578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.503846ms","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":"warn","ts":"2026-02-18T02:43:43.921585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.100743ms","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-18T02:43:43.921601Z","caller":"traceutil/trace.go:171","msg":"trace[247818941] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:618; }","duration":"891.562208ms","start":"2026-02-18T02:43:43.030032Z","end":"2026-02-18T02:43:43.921594Z","steps":["trace[247818941] 'agreement among raft nodes before linearized reading' (duration: 891.465544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.921622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.030018Z","time spent":"891.598238ms","remote":"127.0.0.1:48870","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":"info","ts":"2026-02-18T02:43:43.921608Z","caller":"traceutil/trace.go:171","msg":"trace[1716534621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"890.167105ms","start":"2026-02-18T02:43:43.031434Z","end":"2026-02-18T02:43:43.921601Z","steps":["trace[1716534621] 'agreement among raft nodes before linearized reading' (duration: 890.055081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.921646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.031411Z","time spent":"890.229567ms","remote":"127.0.0.1:49128","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-18T02:43:43.921738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.012566ms","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-18T02:43:43.921759Z","caller":"traceutil/trace.go:171","msg":"trace[1028116355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"907.043196ms","start":"2026-02-18T02:43:43.014709Z","end":"2026-02-18T02:43:43.921753Z","steps":["trace[1028116355] 'agreement among raft nodes before linearized reading' (duration: 906.983595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.921778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.014705Z","time spent":"907.067457ms","remote":"127.0.0.1:49128","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-18T02:43:43.921805Z","caller":"traceutil/trace.go:171","msg":"trace[1213044760] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"613.865371ms","start":"2026-02-18T02:43:43.307932Z","end":"2026-02-18T02:43:43.921797Z","steps":["trace[1213044760] 'process raft request' (duration: 612.739727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.921845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.307926Z","time spent":"613.895981ms","remote":"127.0.0.1:49128","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-18T02:43:43.921921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.622083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T02:43:43.921941Z","caller":"traceutil/trace.go:171","msg":"trace[2121018773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"907.643324ms","start":"2026-02-18T02:43:43.014292Z","end":"2026-02-18T02:43:43.921935Z","steps":["trace[2121018773] 'agreement among raft nodes before linearized reading' (duration: 907.586932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.921959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.014017Z","time spent":"907.937252ms","remote":"127.0.0.1:49128","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-18T02:43:43.922048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.585288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-18T02:43:43.922071Z","caller":"traceutil/trace.go:171","msg":"trace[314131085] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:618; }","duration":"691.64316ms","start":"2026-02-18T02:43:43.230421Z","end":"2026-02-18T02:43:43.922064Z","steps":["trace[314131085] 'agreement among raft nodes before linearized reading' (duration: 691.563017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.922091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.230406Z","time spent":"691.6793ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-18T02:43:43.97727Z","caller":"traceutil/trace.go:171","msg":"trace[590778912] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"514.323792ms","start":"2026-02-18T02:43:43.462929Z","end":"2026-02-18T02:43:43.977252Z","steps":["trace[590778912] 'process raft request' (duration: 514.24319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.977514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.462903Z","time spent":"514.502068ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T02:43:43.977668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.915509ms","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-18T02:43:43.977699Z","caller":"traceutil/trace.go:171","msg":"trace[963375581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"589.97319ms","start":"2026-02-18T02:43:43.387718Z","end":"2026-02-18T02:43:43.977691Z","steps":["trace[963375581] 'agreement among raft nodes before linearized reading' (duration: 589.789364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.977724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.387705Z","time spent":"590.011792ms","remote":"127.0.0.1:49128","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-18T02:43:43.977921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.082868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-18T02:43:43.977949Z","caller":"traceutil/trace.go:171","msg":"trace[155193500] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:620; }","duration":"148.134889ms","start":"2026-02-18T02:43:43.829806Z","end":"2026-02-18T02:43:43.977941Z","steps":["trace[155193500] 'agreement among raft nodes before linearized reading' (duration: 148.067947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.978074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.382025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-18T02:43:43.978106Z","caller":"traceutil/trace.go:171","msg":"trace[1905481937] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:620; }","duration":"347.439247ms","start":"2026-02-18T02:43:43.63066Z","end":"2026-02-18T02:43:43.9781Z","steps":["trace[1905481937] 'agreement among raft nodes before linearized reading' (duration: 347.371265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.978125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.630647Z","time spent":"347.472478ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-02-18T02:43:43.978065Z","caller":"traceutil/trace.go:171","msg":"trace[367454205] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"558.045203ms","start":"2026-02-18T02:43:43.419986Z","end":"2026-02-18T02:43:43.978032Z","steps":["trace[367454205] 'process raft request' (duration: 556.978651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.97824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.470706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.170\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T02:43:43.978271Z","caller":"traceutil/trace.go:171","msg":"trace[1574812253] range","detail":"{range_begin:/registry/masterleases/199.19.213.170; range_end:; response_count:1; response_revision:620; }","duration":"554.517057ms","start":"2026-02-18T02:43:43.423742Z","end":"2026-02-18T02:43:43.978259Z","steps":["trace[1574812253] 'agreement among raft nodes before linearized reading' (duration: 554.457705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:43.978295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.423733Z","time spent":"554.555019ms","remote":"127.0.0.1:48850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.170\" "} {"level":"warn","ts":"2026-02-18T02:43:43.978343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.419971Z","time spent":"558.264679ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:43:44.471701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.314062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:43:44.471924Z","caller":"traceutil/trace.go:171","msg":"trace[60007312] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"511.548093ms","start":"2026-02-18T02:43:43.96036Z","end":"2026-02-18T02:43:44.471909Z","steps":["trace[60007312] 'process raft request' (duration: 511.510461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:44.471993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.960296Z","time spent":"511.666136ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:43:44.472352Z","caller":"traceutil/trace.go:171","msg":"trace[721652151] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"540.596495ms","start":"2026-02-18T02:43:43.931735Z","end":"2026-02-18T02:43:44.472331Z","steps":["trace[721652151] 'process raft request' (duration: 389.587522ms)","trace[721652151] 'compare' (duration: 150.144988ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:44.472434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.931717Z","time spent":"540.683027ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:43:44.472598Z","caller":"traceutil/trace.go:171","msg":"trace[5729046] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"540.816191ms","start":"2026-02-18T02:43:43.931767Z","end":"2026-02-18T02:43:44.472583Z","steps":["trace[5729046] 'process raft request' (duration: 540.015838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:44.47268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.931759Z","time spent":"540.880233ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:43:44.472831Z","caller":"traceutil/trace.go:171","msg":"trace[920183055] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"541.047058ms","start":"2026-02-18T02:43:43.931771Z","end":"2026-02-18T02:43:44.472818Z","steps":["trace[920183055] 'process raft request' (duration: 540.066619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:44.472931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:43.931717Z","time spent":"541.181341ms","remote":"127.0.0.1:48902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T02:43:44.511179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:44.012691Z","time spent":"498.458735ms","remote":"127.0.0.1:48850","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T02:43:44.511732Z","caller":"traceutil/trace.go:171","msg":"trace[649840304] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"438.988266ms","start":"2026-02-18T02:43:44.072723Z","end":"2026-02-18T02:43:44.511711Z","steps":["trace[649840304] 'process raft request' (duration: 438.328746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:44.51188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:44.072706Z","time spent":"439.080219ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:43:44.512001Z","caller":"traceutil/trace.go:171","msg":"trace[935096361] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:640; }","duration":"406.368566ms","start":"2026-02-18T02:43:44.105617Z","end":"2026-02-18T02:43:44.511985Z","steps":["trace[935096361] 'read index received' (duration: 215.613333ms)","trace[935096361] 'applied index is now lower than readState.Index' (duration: 190.754683ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:44.512555Z","caller":"traceutil/trace.go:171","msg":"trace[564112022] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"367.238075ms","start":"2026-02-18T02:43:44.14531Z","end":"2026-02-18T02:43:44.512548Z","steps":["trace[564112022] 'process raft request' (duration: 365.901846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:44.512623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:44.145293Z","time spent":"367.277356ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:43:44.512724Z","caller":"traceutil/trace.go:171","msg":"trace[1257012752] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"313.929697ms","start":"2026-02-18T02:43:44.19879Z","end":"2026-02-18T02:43:44.512719Z","steps":["trace[1257012752] 'process raft request' (duration: 312.640788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:44.512752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:44.198773Z","time spent":"313.963168ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:43:44.51286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.240962ms","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-18T02:43:44.512885Z","caller":"traceutil/trace.go:171","msg":"trace[646717525] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:627; }","duration":"407.288204ms","start":"2026-02-18T02:43:44.105592Z","end":"2026-02-18T02:43:44.51288Z","steps":["trace[646717525] 'agreement among raft nodes before linearized reading' (duration: 407.214111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:44.5129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:44.105563Z","time spent":"407.333695ms","remote":"127.0.0.1:48870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-18T02:43:44.512993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.100039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-18T02:43:44.513008Z","caller":"traceutil/trace.go:171","msg":"trace[1471440761] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:627; }","duration":"283.14208ms","start":"2026-02-18T02:43:44.22986Z","end":"2026-02-18T02:43:44.513002Z","steps":["trace[1471440761] 'agreement among raft nodes before linearized reading' (duration: 283.101319ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:44.715401Z","caller":"traceutil/trace.go:171","msg":"trace[669333910] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"202.694062ms","start":"2026-02-18T02:43:44.512671Z","end":"2026-02-18T02:43:44.715365Z","steps":["trace[669333910] 'process raft request' (duration: 108.645475ms)","trace[669333910] 'compare' (duration: 93.931744ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:28.823847Z","caller":"traceutil/trace.go:171","msg":"trace[1624407439] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"126.367111ms","start":"2026-02-18T02:44:28.697458Z","end":"2026-02-18T02:44:28.823826Z","steps":["trace[1624407439] 'process raft request' (duration: 90.345154ms)","trace[1624407439] 'compare' (duration: 35.728661ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:30.980304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.565974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T02:44:30.98069Z","caller":"traceutil/trace.go:171","msg":"trace[1170184024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:848; }","duration":"153.009993ms","start":"2026-02-18T02:44:30.82766Z","end":"2026-02-18T02:44:30.98067Z","steps":["trace[1170184024] 'range keys from in-memory index tree' (duration: 152.43726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:30.981138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.506303ms","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-18T02:44:30.981349Z","caller":"traceutil/trace.go:171","msg":"trace[170354950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:848; }","duration":"111.742577ms","start":"2026-02-18T02:44:30.869591Z","end":"2026-02-18T02:44:30.981334Z","steps":["trace[170354950] 'range keys from in-memory index tree' (duration: 111.398921ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:31.149704Z","caller":"traceutil/trace.go:171","msg":"trace[1869021403] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"161.455786ms","start":"2026-02-18T02:44:30.988225Z","end":"2026-02-18T02:44:31.149681Z","steps":["trace[1869021403] 'process raft request' (duration: 153.244179ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:31.150366Z","caller":"traceutil/trace.go:171","msg":"trace[1699367126] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"162.055499ms","start":"2026-02-18T02:44:30.988285Z","end":"2026-02-18T02:44:31.150341Z","steps":["trace[1699367126] 'process raft request' (duration: 161.353234ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:33.36095Z","caller":"traceutil/trace.go:171","msg":"trace[1175915235] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"198.778272ms","start":"2026-02-18T02:44:33.162147Z","end":"2026-02-18T02:44:33.360925Z","steps":["trace[1175915235] 'process raft request' (duration: 178.545835ms)","trace[1175915235] 'compare' (duration: 19.947161ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:39.494439Z","caller":"traceutil/trace.go:171","msg":"trace[1046252693] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"112.130801ms","start":"2026-02-18T02:44:39.382287Z","end":"2026-02-18T02:44:39.494418Z","steps":["trace[1046252693] 'process raft request' (duration: 111.884406ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:45:02.648956Z","caller":"traceutil/trace.go:171","msg":"trace[100106579] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"275.164098ms","start":"2026-02-18T02:45:02.373761Z","end":"2026-02-18T02:45:02.648926Z","steps":["trace[100106579] 'process raft request' (duration: 274.916724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:09.423653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.739312ms","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-18T02:45:09.423719Z","caller":"traceutil/trace.go:171","msg":"trace[1167095273] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:994; }","duration":"236.865314ms","start":"2026-02-18T02:45:09.186842Z","end":"2026-02-18T02:45:09.423707Z","steps":["trace[1167095273] 'count revisions from in-memory index tree' (duration: 236.63949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:09.42373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.367745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T02:45:09.423792Z","caller":"traceutil/trace.go:171","msg":"trace[1586050416] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:994; }","duration":"195.455857ms","start":"2026-02-18T02:45:09.22832Z","end":"2026-02-18T02:45:09.423776Z","steps":["trace[1586050416] 'range keys from in-memory index tree' (duration: 195.176192ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:45:09.538907Z","caller":"traceutil/trace.go:171","msg":"trace[1819421962] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"110.80888ms","start":"2026-02-18T02:45:09.428082Z","end":"2026-02-18T02:45:09.538891Z","steps":["trace[1819421962] 'process raft request' (duration: 110.711038ms)"],"step_count":1}