{"level":"warn","ts":"2026-02-11T02:22:33.977255Z","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-11T02:22:33.977604Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.74: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.74:2380","--initial-cluster=instance=https://199.19.213.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.74: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-11T02:22:33.977732Z","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-11T02:22:33.977797Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.74:2380"]} {"level":"info","ts":"2026-02-11T02:22:33.97785Z","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-11T02:22:33.978611Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"]} {"level":"info","ts":"2026-02-11T02:22:33.97876Z","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.74:2380"],"listen-peer-urls":["https://199.19.213.74:2380"],"advertise-client-urls":["https://199.19.213.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.74: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-11T02:22:33.984332Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.25325ms"} {"level":"info","ts":"2026-02-11T02:22:33.993387Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d4171f3ea72ed207","cluster-id":"d40b55e346dc63dc"} {"level":"info","ts":"2026-02-11T02:22:33.993477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T02:22:33.993568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became follower at term 0"} {"level":"info","ts":"2026-02-11T02:22:33.993608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d4171f3ea72ed207 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T02:22:33.993666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became follower at term 1"} {"level":"info","ts":"2026-02-11T02:22:33.993737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=(15282718214458364423)"} {"level":"warn","ts":"2026-02-11T02:22:34.006384Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T02:22:34.019225Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T02:22:34.024445Z","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-11T02:22:34.029473Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d4171f3ea72ed207","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T02:22:34.03087Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d4171f3ea72ed207","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T02:22:34.031988Z","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-11T02:22:34.032056Z","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-11T02:22:34.032117Z","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-11T02:22:34.032317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=(15282718214458364423)"} {"level":"info","ts":"2026-02-11T02:22:34.032435Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d40b55e346dc63dc","local-member-id":"d4171f3ea72ed207","added-peer-id":"d4171f3ea72ed207","added-peer-peer-urls":["https://199.19.213.74:2380"]} {"level":"info","ts":"2026-02-11T02:22:34.033728Z","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-11T02:22:34.037444Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d4171f3ea72ed207","initial-advertise-peer-urls":["https://199.19.213.74:2380"],"listen-peer-urls":["https://199.19.213.74:2380"],"advertise-client-urls":["https://199.19.213.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T02:22:34.037296Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.74:2380"} {"level":"info","ts":"2026-02-11T02:22:34.038435Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.74:2380"} {"level":"info","ts":"2026-02-11T02:22:34.038341Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T02:22:34.694602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T02:22:34.69465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T02:22:34.694666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 received MsgPreVoteResp from d4171f3ea72ed207 at term 1"} {"level":"info","ts":"2026-02-11T02:22:34.694677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became candidate at term 2"} {"level":"info","ts":"2026-02-11T02:22:34.694682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 received MsgVoteResp from d4171f3ea72ed207 at term 2"} {"level":"info","ts":"2026-02-11T02:22:34.694691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became leader at term 2"} {"level":"info","ts":"2026-02-11T02:22:34.694703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d4171f3ea72ed207 elected leader d4171f3ea72ed207 at term 2"} {"level":"info","ts":"2026-02-11T02:22:34.697882Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:22:34.705418Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d4171f3ea72ed207","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.74:2379]}","request-path":"/0/members/d4171f3ea72ed207/attributes","cluster-id":"d40b55e346dc63dc","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T02:22:34.705439Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T02:22:34.707427Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d40b55e346dc63dc","local-member-id":"d4171f3ea72ed207","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:22:34.707477Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:22:34.707489Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:22:34.707497Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T02:22:34.715224Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.74:2379"} {"level":"info","ts":"2026-02-11T02:22:34.717633Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T02:22:34.729444Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T02:22:34.72946Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-11T02:22:44.650984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.293714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:22:44.651054Z","caller":"traceutil/trace.go:171","msg":"trace[1956275687] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:294; }","duration":"752.404937ms","start":"2026-02-11T02:22:43.898635Z","end":"2026-02-11T02:22:44.65104Z","steps":["trace[1956275687] 'range keys from in-memory index tree' (duration: 752.244594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:22:44.651105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:22:43.898622Z","time spent":"752.476769ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" "} {"level":"warn","ts":"2026-02-11T02:22:44.651189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.780183ms","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-11T02:22:44.651266Z","caller":"traceutil/trace.go:171","msg":"trace[288250595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:294; }","duration":"156.04221ms","start":"2026-02-11T02:22:44.495208Z","end":"2026-02-11T02:22:44.65125Z","steps":["trace[288250595] 'range keys from in-memory index tree' (duration: 155.63027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:22:44.651389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.724247ms","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-11T02:22:44.65212Z","caller":"traceutil/trace.go:171","msg":"trace[2094176840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:294; }","duration":"325.487535ms","start":"2026-02-11T02:22:44.326623Z","end":"2026-02-11T02:22:44.652111Z","steps":["trace[2094176840] 'range keys from in-memory index tree' (duration: 324.613573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:22:44.652145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:22:44.326603Z","time spent":"325.534046ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T02:22:44.65141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.076944ms","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-11T02:22:44.652259Z","caller":"traceutil/trace.go:171","msg":"trace[290306907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:294; }","duration":"233.970757ms","start":"2026-02-11T02:22:44.418281Z","end":"2026-02-11T02:22:44.652252Z","steps":["trace[290306907] 'range keys from in-memory index tree' (duration: 232.914281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:22:44.651426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.097581ms","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-11T02:22:44.65237Z","caller":"traceutil/trace.go:171","msg":"trace[1458492023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:294; }","duration":"364.063675ms","start":"2026-02-11T02:22:44.2883Z","end":"2026-02-11T02:22:44.652364Z","steps":["trace[1458492023] 'range keys from in-memory index tree' (duration: 362.550578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:22:44.652389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:22:44.288286Z","time spent":"364.097275ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-11T02:22:46.288709Z","caller":"traceutil/trace.go:171","msg":"trace[121902804] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"104.648841ms","start":"2026-02-11T02:22:46.184036Z","end":"2026-02-11T02:22:46.288684Z","steps":["trace[121902804] 'process raft request' (duration: 104.411845ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:22:50.567025Z","caller":"traceutil/trace.go:171","msg":"trace[816904606] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"311.397054ms","start":"2026-02-11T02:22:50.255594Z","end":"2026-02-11T02:22:50.566991Z","steps":["trace[816904606] 'read index received' (duration: 311.207219ms)","trace[816904606] 'applied index is now lower than readState.Index' (duration: 188.865µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:22:50.567133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.521707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:22:50.567158Z","caller":"traceutil/trace.go:171","msg":"trace[542568696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"311.58782ms","start":"2026-02-11T02:22:50.255561Z","end":"2026-02-11T02:22:50.567149Z","steps":["trace[542568696] 'agreement among raft nodes before linearized reading' (duration: 311.512828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:22:50.567198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:22:50.255548Z","time spent":"311.643921ms","remote":"127.0.0.1:33088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T02:22:50.567291Z","caller":"traceutil/trace.go:171","msg":"trace[1168248193] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"457.50481ms","start":"2026-02-11T02:22:50.109766Z","end":"2026-02-11T02:22:50.567271Z","steps":["trace[1168248193] 'process raft request' (duration: 457.085518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:22:50.567735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:22:50.109741Z","time spent":"457.597773ms","remote":"127.0.0.1:33246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4049,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:23:06.897397Z","caller":"traceutil/trace.go:171","msg":"trace[396650744] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"110.741833ms","start":"2026-02-11T02:23:06.786641Z","end":"2026-02-11T02:23:06.897383Z","steps":["trace[396650744] 'read index received' (duration: 110.619379ms)","trace[396650744] 'applied index is now lower than readState.Index' (duration: 121.974µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:06.897598Z","caller":"traceutil/trace.go:171","msg":"trace[1227303381] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"121.645829ms","start":"2026-02-11T02:23:06.775944Z","end":"2026-02-11T02:23:06.89759Z","steps":["trace[1227303381] 'process raft request' (duration: 121.353709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:06.897784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.131977ms","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-11T02:23:06.897802Z","caller":"traceutil/trace.go:171","msg":"trace[1122162128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:477; }","duration":"111.180038ms","start":"2026-02-11T02:23:06.786617Z","end":"2026-02-11T02:23:06.897797Z","steps":["trace[1122162128] 'agreement among raft nodes before linearized reading' (duration: 111.100545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:06.897891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.65887ms","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-11T02:23:06.897904Z","caller":"traceutil/trace.go:171","msg":"trace[640323958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:477; }","duration":"110.6844ms","start":"2026-02-11T02:23:06.787215Z","end":"2026-02-11T02:23:06.8979Z","steps":["trace[640323958] 'agreement among raft nodes before linearized reading' (duration: 110.646049ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:07.045388Z","caller":"traceutil/trace.go:171","msg":"trace[1217762731] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"143.570603ms","start":"2026-02-11T02:23:06.901801Z","end":"2026-02-11T02:23:07.045372Z","steps":["trace[1217762731] 'process raft request' (duration: 143.392327ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:07.070722Z","caller":"traceutil/trace.go:171","msg":"trace[2075434387] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:495; }","duration":"122.235788ms","start":"2026-02-11T02:23:06.948466Z","end":"2026-02-11T02:23:07.070702Z","steps":["trace[2075434387] 'read index received' (duration: 96.812277ms)","trace[2075434387] 'applied index is now lower than readState.Index' (duration: 25.422601ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:07.070954Z","caller":"traceutil/trace.go:171","msg":"trace[2060550395] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"168.719995ms","start":"2026-02-11T02:23:06.902224Z","end":"2026-02-11T02:23:07.070944Z","steps":["trace[2060550395] 'process raft request' (duration: 168.381383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:07.071236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.760607ms","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-11T02:23:07.07127Z","caller":"traceutil/trace.go:171","msg":"trace[1252139021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:479; }","duration":"122.828559ms","start":"2026-02-11T02:23:06.948433Z","end":"2026-02-11T02:23:07.071262Z","steps":["trace[1252139021] 'agreement among raft nodes before linearized reading' (duration: 122.719245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:07.071403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.010722ms","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-11T02:23:07.071421Z","caller":"traceutil/trace.go:171","msg":"trace[255195339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:479; }","duration":"113.048163ms","start":"2026-02-11T02:23:06.958368Z","end":"2026-02-11T02:23:07.071416Z","steps":["trace[255195339] 'agreement among raft nodes before linearized reading' (duration: 112.998351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:07.490925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.287726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:23:07.49099Z","caller":"traceutil/trace.go:171","msg":"trace[2070673607] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"414.809747ms","start":"2026-02-11T02:23:07.076168Z","end":"2026-02-11T02:23:07.490978Z","steps":["trace[2070673607] 'process raft request' (duration: 86.334914ms)","trace[2070673607] 'compare' (duration: 328.218674ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:23:07.49102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:07.076155Z","time spent":"414.851499ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:23:07.492253Z","caller":"traceutil/trace.go:171","msg":"trace[346637592] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"414.131733ms","start":"2026-02-11T02:23:07.078113Z","end":"2026-02-11T02:23:07.492244Z","steps":["trace[346637592] 'process raft request' (duration: 414.079941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:07.492617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:07.078105Z","time spent":"414.170985ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:23:08.217352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.306511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:23:08.21743Z","caller":"traceutil/trace.go:171","msg":"trace[1402511291] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"138.364716ms","start":"2026-02-11T02:23:08.079051Z","end":"2026-02-11T02:23:08.217416Z","steps":["trace[1402511291] 'process raft request' (duration: 25.94057ms)","trace[1402511291] 'compare' (duration: 112.200498ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:08.225797Z","caller":"traceutil/trace.go:171","msg":"trace[263799927] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"142.540887ms","start":"2026-02-11T02:23:08.083241Z","end":"2026-02-11T02:23:08.225782Z","steps":["trace[263799927] 'process raft request' (duration: 142.490165ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:08.22616Z","caller":"traceutil/trace.go:171","msg":"trace[729324403] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"146.874505ms","start":"2026-02-11T02:23:08.079257Z","end":"2026-02-11T02:23:08.226132Z","steps":["trace[729324403] 'process raft request' (duration: 146.411008ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:09.286354Z","caller":"traceutil/trace.go:171","msg":"trace[1834209498] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"210.888631ms","start":"2026-02-11T02:23:09.075451Z","end":"2026-02-11T02:23:09.28634Z","steps":["trace[1834209498] 'read index received' (duration: 210.761726ms)","trace[1834209498] 'applied index is now lower than readState.Index' (duration: 126.405µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:23:09.286513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.037046ms","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-11T02:23:09.286531Z","caller":"traceutil/trace.go:171","msg":"trace[236515827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"211.077947ms","start":"2026-02-11T02:23:09.075447Z","end":"2026-02-11T02:23:09.286525Z","steps":["trace[236515827] 'agreement among raft nodes before linearized reading' (duration: 210.961253ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:09.286717Z","caller":"traceutil/trace.go:171","msg":"trace[831972651] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"236.617193ms","start":"2026-02-11T02:23:09.050095Z","end":"2026-02-11T02:23:09.286712Z","steps":["trace[831972651] 'process raft request' (duration: 236.161486ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:09.686654Z","caller":"traceutil/trace.go:171","msg":"trace[1095090465] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"187.483713ms","start":"2026-02-11T02:23:09.499153Z","end":"2026-02-11T02:23:09.686637Z","steps":["trace[1095090465] 'process raft request' (duration: 107.178689ms)","trace[1095090465] 'compare' (duration: 80.226441ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:10.047145Z","caller":"traceutil/trace.go:171","msg":"trace[1015559911] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"119.191006ms","start":"2026-02-11T02:23:09.927937Z","end":"2026-02-11T02:23:10.047128Z","steps":["trace[1015559911] 'process raft request' (duration: 37.11072ms)","trace[1015559911] 'compare' (duration: 81.997853ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:11.467609Z","caller":"traceutil/trace.go:171","msg":"trace[336842802] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"113.75544ms","start":"2026-02-11T02:23:11.353831Z","end":"2026-02-11T02:23:11.467587Z","steps":["trace[336842802] 'process raft request' (duration: 88.928451ms)","trace[336842802] 'compare' (duration: 24.748246ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:13.797843Z","caller":"traceutil/trace.go:171","msg":"trace[1588312825] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"546.142501ms","start":"2026-02-11T02:23:13.251684Z","end":"2026-02-11T02:23:13.797827Z","steps":["trace[1588312825] 'read index received' (duration: 455.147234ms)","trace[1588312825] 'applied index is now lower than readState.Index' (duration: 90.994457ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:23:13.797951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.250955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:23:13.797968Z","caller":"traceutil/trace.go:171","msg":"trace[290027636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"546.311417ms","start":"2026-02-11T02:23:13.25165Z","end":"2026-02-11T02:23:13.797962Z","steps":["trace[290027636] 'agreement among raft nodes before linearized reading' (duration: 546.259095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:13.797985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:13.251635Z","time spent":"546.346539ms","remote":"127.0.0.1:33088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T02:23:13.798185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.254947ms","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-11T02:23:13.798199Z","caller":"traceutil/trace.go:171","msg":"trace[1302780633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"444.290538ms","start":"2026-02-11T02:23:13.353903Z","end":"2026-02-11T02:23:13.798193Z","steps":["trace[1302780633] 'agreement among raft nodes before linearized reading' (duration: 444.225296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:13.798218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:13.35389Z","time spent":"444.31809ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T02:23:13.798384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.650958ms","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-11T02:23:13.798419Z","caller":"traceutil/trace.go:171","msg":"trace[875081339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"188.717371ms","start":"2026-02-11T02:23:13.609696Z","end":"2026-02-11T02:23:13.798413Z","steps":["trace[875081339] 'agreement among raft nodes before linearized reading' (duration: 188.662639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:13.798494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.823837ms","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-11T02:23:13.798505Z","caller":"traceutil/trace.go:171","msg":"trace[1089855886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"327.870789ms","start":"2026-02-11T02:23:13.470631Z","end":"2026-02-11T02:23:13.798502Z","steps":["trace[1089855886] 'agreement among raft nodes before linearized reading' (duration: 327.835957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:13.798518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:13.470611Z","time spent":"327.90406ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-11T02:23:13.978165Z","caller":"traceutil/trace.go:171","msg":"trace[1436675153] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"174.25897ms","start":"2026-02-11T02:23:13.803888Z","end":"2026-02-11T02:23:13.978147Z","steps":["trace[1436675153] 'process raft request' (duration: 174.124385ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:13.978577Z","caller":"traceutil/trace.go:171","msg":"trace[767894636] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"172.997471ms","start":"2026-02-11T02:23:13.805574Z","end":"2026-02-11T02:23:13.978572Z","steps":["trace[767894636] 'process raft request' (duration: 172.522573ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:13.979884Z","caller":"traceutil/trace.go:171","msg":"trace[738472390] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"173.138656ms","start":"2026-02-11T02:23:13.806738Z","end":"2026-02-11T02:23:13.979877Z","steps":["trace[738472390] 'process raft request' (duration: 171.378139ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:18.075829Z","caller":"traceutil/trace.go:171","msg":"trace[284369123] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"381.299386ms","start":"2026-02-11T02:23:17.694516Z","end":"2026-02-11T02:23:18.075816Z","steps":["trace[284369123] 'read index received' (duration: 381.089827ms)","trace[284369123] 'applied index is now lower than readState.Index' (duration: 208.778µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:18.075937Z","caller":"traceutil/trace.go:171","msg":"trace[1256994623] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"629.858617ms","start":"2026-02-11T02:23:17.446071Z","end":"2026-02-11T02:23:18.07593Z","steps":["trace[1256994623] 'process raft request' (duration: 629.540004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:18.07602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:17.446057Z","time spent":"629.896639ms","remote":"127.0.0.1:33246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:23:18.076195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.044699ms","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-11T02:23:18.076256Z","caller":"traceutil/trace.go:171","msg":"trace[2089331134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"244.110902ms","start":"2026-02-11T02:23:17.832131Z","end":"2026-02-11T02:23:18.076242Z","steps":["trace[2089331134] 'agreement among raft nodes before linearized reading' (duration: 243.961076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:18.076414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.895589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-11T02:23:18.076441Z","caller":"traceutil/trace.go:171","msg":"trace[644660959] range","detail":"{range_begin:/registry/masterleases/199.19.213.74; range_end:; response_count:1; response_revision:526; }","duration":"381.950401ms","start":"2026-02-11T02:23:17.694483Z","end":"2026-02-11T02:23:18.076433Z","steps":["trace[644660959] 'agreement among raft nodes before linearized reading' (duration: 381.879578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:18.076474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:17.694468Z","time spent":"382.000784ms","remote":"127.0.0.1:33112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.74\" "} {"level":"warn","ts":"2026-02-11T02:23:18.622595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.072031ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-11T02:23:18.622657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:18.080872Z","time spent":"541.780967ms","remote":"127.0.0.1:33112","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T02:23:18.847011Z","caller":"traceutil/trace.go:171","msg":"trace[1776236461] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:546; }","duration":"590.691685ms","start":"2026-02-11T02:23:18.256294Z","end":"2026-02-11T02:23:18.846986Z","steps":["trace[1776236461] 'read index received' (duration: 60.215461ms)","trace[1776236461] 'applied index is now lower than readState.Index' (duration: 530.475384ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:23:18.847165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.82485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:23:18.847191Z","caller":"traceutil/trace.go:171","msg":"trace[1523170982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"590.936785ms","start":"2026-02-11T02:23:18.256245Z","end":"2026-02-11T02:23:18.847182Z","steps":["trace[1523170982] 'agreement among raft nodes before linearized reading' (duration: 590.829901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:18.847218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:18.256223Z","time spent":"590.987587ms","remote":"127.0.0.1:33094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T02:23:18.847261Z","caller":"traceutil/trace.go:171","msg":"trace[1240831559] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"760.241282ms","start":"2026-02-11T02:23:18.086991Z","end":"2026-02-11T02:23:18.847233Z","steps":["trace[1240831559] 'process raft request' (duration: 759.886038ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:18.847425Z","caller":"traceutil/trace.go:171","msg":"trace[314204032] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"758.155978ms","start":"2026-02-11T02:23:18.089259Z","end":"2026-02-11T02:23:18.847415Z","steps":["trace[314204032] 'process raft request' (duration: 757.673039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:18.847434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:18.086981Z","time spent":"760.360067ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:23:18.847505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:18.089252Z","time spent":"758.194091ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:23:18.847563Z","caller":"traceutil/trace.go:171","msg":"trace[1134742525] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"757.956381ms","start":"2026-02-11T02:23:18.089601Z","end":"2026-02-11T02:23:18.847557Z","steps":["trace[1134742525] 'process raft request' (duration: 757.355867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:18.847591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:18.089594Z","time spent":"757.980782ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:23:18.847723Z","caller":"traceutil/trace.go:171","msg":"trace[1191440138] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"766.108757ms","start":"2026-02-11T02:23:18.081604Z","end":"2026-02-11T02:23:18.847713Z","steps":["trace[1191440138] 'process raft request' (duration: 743.748262ms)","trace[1191440138] 'compare' (duration: 21.370916ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:23:18.847763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:18.081586Z","time spent":"766.155869ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:23:18.859802Z","caller":"traceutil/trace.go:171","msg":"trace[697210650] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"235.866732ms","start":"2026-02-11T02:23:18.623919Z","end":"2026-02-11T02:23:18.859786Z","steps":["trace[697210650] 'process raft request' (duration: 235.762608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:21.352979Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.038310063s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T02:23:21.374871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.120101122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:23:21.374915Z","caller":"traceutil/trace.go:171","msg":"trace[1320875505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"2.120193357s","start":"2026-02-11T02:23:19.254712Z","end":"2026-02-11T02:23:21.374905Z","steps":["trace[1320875505] 'range keys from in-memory index tree' (duration: 2.120008849s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:21.374935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:19.25469Z","time spent":"2.120240819s","remote":"127.0.0.1:33088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T02:23:21.375471Z","caller":"traceutil/trace.go:171","msg":"trace[22863691] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"523.28018ms","start":"2026-02-11T02:23:20.852184Z","end":"2026-02-11T02:23:21.375464Z","steps":["trace[22863691] 'read index received' (duration: 500.905022ms)","trace[22863691] 'applied index is now lower than readState.Index' (duration: 22.374698ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:21.375586Z","caller":"traceutil/trace.go:171","msg":"trace[1648012854] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.060970514s","start":"2026-02-11T02:23:20.314604Z","end":"2026-02-11T02:23:21.375574Z","steps":["trace[1648012854] 'process raft request' (duration: 1.038537063s)","trace[1648012854] 'compare' (duration: 22.221482ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:23:21.375632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:20.314587Z","time spent":"1.061010845s","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:23:21.375775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.591653ms","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-11T02:23:21.37579Z","caller":"traceutil/trace.go:171","msg":"trace[705124089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"523.646366ms","start":"2026-02-11T02:23:20.852138Z","end":"2026-02-11T02:23:21.375785Z","steps":["trace[705124089] 'agreement among raft nodes before linearized reading' (duration: 523.584403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:21.375802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:20.852115Z","time spent":"523.683947ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T02:23:21.375875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.346491ms","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-11T02:23:21.375888Z","caller":"traceutil/trace.go:171","msg":"trace[869650651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"520.382103ms","start":"2026-02-11T02:23:20.855502Z","end":"2026-02-11T02:23:21.375884Z","steps":["trace[869650651] 'agreement among raft nodes before linearized reading' (duration: 520.346041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:21.375899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:20.855491Z","time spent":"520.405543ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T02:23:21.375974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.923994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:23:21.375986Z","caller":"traceutil/trace.go:171","msg":"trace[455949863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"120.968516ms","start":"2026-02-11T02:23:21.255013Z","end":"2026-02-11T02:23:21.375982Z","steps":["trace[455949863] 'agreement among raft nodes before linearized reading' (duration: 120.949655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:21.376073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.483306ms","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-11T02:23:21.376086Z","caller":"traceutil/trace.go:171","msg":"trace[1629515890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"520.507958ms","start":"2026-02-11T02:23:20.855575Z","end":"2026-02-11T02:23:21.376083Z","steps":["trace[1629515890] 'agreement among raft nodes before linearized reading' (duration: 520.471756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:21.376097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:20.85557Z","time spent":"520.524198ms","remote":"127.0.0.1:33332","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-11T02:23:21.37616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.592351ms","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-11T02:23:21.37617Z","caller":"traceutil/trace.go:171","msg":"trace[383809522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"520.617742ms","start":"2026-02-11T02:23:20.855549Z","end":"2026-02-11T02:23:21.376167Z","steps":["trace[383809522] 'agreement among raft nodes before linearized reading' (duration: 520.587671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:21.376181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:20.855543Z","time spent":"520.635063ms","remote":"127.0.0.1:33332","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":"info","ts":"2026-02-11T02:23:24.017424Z","caller":"traceutil/trace.go:171","msg":"trace[633037607] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"270.915583ms","start":"2026-02-11T02:23:23.746493Z","end":"2026-02-11T02:23:24.017408Z","steps":["trace[633037607] 'read index received' (duration: 265.015115ms)","trace[633037607] 'applied index is now lower than readState.Index' (duration: 5.899838ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:24.017548Z","caller":"traceutil/trace.go:171","msg":"trace[939690701] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"278.680777ms","start":"2026-02-11T02:23:23.738861Z","end":"2026-02-11T02:23:24.017541Z","steps":["trace[939690701] 'process raft request' (duration: 272.682145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:24.017772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.233436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-bks5d\" ","response":"range_response_count:1 size:4818"} {"level":"info","ts":"2026-02-11T02:23:24.01779Z","caller":"traceutil/trace.go:171","msg":"trace[128979765] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-bks5d; range_end:; response_count:1; response_revision:566; }","duration":"271.317919ms","start":"2026-02-11T02:23:23.746468Z","end":"2026-02-11T02:23:24.017785Z","steps":["trace[128979765] 'agreement among raft nodes before linearized reading' (duration: 271.183304ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:24.037491Z","caller":"traceutil/trace.go:171","msg":"trace[1134150117] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"287.538623ms","start":"2026-02-11T02:23:23.749931Z","end":"2026-02-11T02:23:24.037469Z","steps":["trace[1134150117] 'process raft request' (duration: 287.340494ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:24.037668Z","caller":"traceutil/trace.go:171","msg":"trace[1792981686] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"291.098691ms","start":"2026-02-11T02:23:23.746564Z","end":"2026-02-11T02:23:24.037662Z","steps":["trace[1792981686] 'process raft request' (duration: 290.571149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:24.037687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.984111ms","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-11T02:23:24.037869Z","caller":"traceutil/trace.go:171","msg":"trace[1596130317] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"243.670973ms","start":"2026-02-11T02:23:23.794192Z","end":"2026-02-11T02:23:24.037863Z","steps":["trace[1596130317] 'process raft request' (duration: 243.1305ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:24.03777Z","caller":"traceutil/trace.go:171","msg":"trace[1018885961] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:570; }","duration":"288.104766ms","start":"2026-02-11T02:23:23.749631Z","end":"2026-02-11T02:23:24.037736Z","steps":["trace[1018885961] 'agreement among raft nodes before linearized reading' (duration: 287.775132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:25.587224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.543604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:23:25.587323Z","caller":"traceutil/trace.go:171","msg":"trace[1587201068] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"112.362438ms","start":"2026-02-11T02:23:25.474949Z","end":"2026-02-11T02:23:25.587312Z","steps":["trace[1587201068] 'process raft request' (duration: 112.333946ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:25.587492Z","caller":"traceutil/trace.go:171","msg":"trace[406252423] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"349.233567ms","start":"2026-02-11T02:23:25.238251Z","end":"2026-02-11T02:23:25.587484Z","steps":["trace[406252423] 'process raft request' (duration: 164.343029ms)","trace[406252423] 'compare' (duration: 184.47368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:23:25.587518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:23:25.238236Z","time spent":"349.266058ms","remote":"127.0.0.1:33126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:23:25.587593Z","caller":"traceutil/trace.go:171","msg":"trace[2059854097] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"193.931791ms","start":"2026-02-11T02:23:25.393656Z","end":"2026-02-11T02:23:25.587588Z","steps":["trace[2059854097] 'read index received' (duration: 8.888647ms)","trace[2059854097] 'applied index is now lower than readState.Index' (duration: 185.042664ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:23:25.58792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.266516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-11T02:23:25.587939Z","caller":"traceutil/trace.go:171","msg":"trace[1127256365] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:592; }","duration":"194.314308ms","start":"2026-02-11T02:23:25.393618Z","end":"2026-02-11T02:23:25.587932Z","steps":["trace[1127256365] 'agreement among raft nodes before linearized reading' (duration: 194.247885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:25.590592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.710652ms","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-11T02:23:25.590634Z","caller":"traceutil/trace.go:171","msg":"trace[965653650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"176.763174ms","start":"2026-02-11T02:23:25.413865Z","end":"2026-02-11T02:23:25.590629Z","steps":["trace[965653650] 'agreement among raft nodes before linearized reading' (duration: 176.694591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:25.590763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.13733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5hvxm\" ","response":"range_response_count:1 size:17666"} {"level":"info","ts":"2026-02-11T02:23:25.590828Z","caller":"traceutil/trace.go:171","msg":"trace[1801849865] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5hvxm; range_end:; response_count:1; response_revision:592; }","duration":"117.201402ms","start":"2026-02-11T02:23:25.473621Z","end":"2026-02-11T02:23:25.590822Z","steps":["trace[1801849865] 'agreement among raft nodes before linearized reading' (duration: 117.107258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:25.591034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.83122ms","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-11T02:23:25.592476Z","caller":"traceutil/trace.go:171","msg":"trace[752158639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"179.281541ms","start":"2026-02-11T02:23:25.413188Z","end":"2026-02-11T02:23:25.59247Z","steps":["trace[752158639] 'agreement among raft nodes before linearized reading' (duration: 177.815779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:25.591996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.332658ms","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-11T02:23:25.593854Z","caller":"traceutil/trace.go:171","msg":"trace[594303340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"183.193647ms","start":"2026-02-11T02:23:25.410615Z","end":"2026-02-11T02:23:25.593808Z","steps":["trace[594303340] 'agreement among raft nodes before linearized reading' (duration: 181.201842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:25.592428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.99109ms","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-11T02:23:25.598184Z","caller":"traceutil/trace.go:171","msg":"trace[993938246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"189.764315ms","start":"2026-02-11T02:23:25.408392Z","end":"2026-02-11T02:23:25.598157Z","steps":["trace[993938246] 'agreement among raft nodes before linearized reading' (duration: 182.129582ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:25.809611Z","caller":"traceutil/trace.go:171","msg":"trace[269456471] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"204.600213ms","start":"2026-02-11T02:23:25.604995Z","end":"2026-02-11T02:23:25.809595Z","steps":["trace[269456471] 'process raft request' (duration: 204.486938ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:25.809683Z","caller":"traceutil/trace.go:171","msg":"trace[443678768] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"211.204373ms","start":"2026-02-11T02:23:25.59846Z","end":"2026-02-11T02:23:25.809665Z","steps":["trace[443678768] 'process raft request' (duration: 153.002209ms)","trace[443678768] 'compare' (duration: 57.940273ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:25.809807Z","caller":"traceutil/trace.go:171","msg":"trace[1118179141] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"204.745909ms","start":"2026-02-11T02:23:25.605054Z","end":"2026-02-11T02:23:25.8098Z","steps":["trace[1118179141] 'process raft request' (duration: 204.462687ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:25.809849Z","caller":"traceutil/trace.go:171","msg":"trace[2090513008] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:616; }","duration":"204.091931ms","start":"2026-02-11T02:23:25.605751Z","end":"2026-02-11T02:23:25.809843Z","steps":["trace[2090513008] 'read index received' (duration: 11.705856ms)","trace[2090513008] 'applied index is now lower than readState.Index' (duration: 192.385415ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:25.809877Z","caller":"traceutil/trace.go:171","msg":"trace[2067379681] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"202.388719ms","start":"2026-02-11T02:23:25.607484Z","end":"2026-02-11T02:23:25.809872Z","steps":["trace[2067379681] 'process raft request' (duration: 202.050764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:25.809962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.198796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-11T02:23:25.809981Z","caller":"traceutil/trace.go:171","msg":"trace[437314008] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:598; }","duration":"204.245187ms","start":"2026-02-11T02:23:25.605729Z","end":"2026-02-11T02:23:25.809974Z","steps":["trace[437314008] 'agreement among raft nodes before linearized reading' (duration: 204.133802ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:25.810073Z","caller":"traceutil/trace.go:171","msg":"trace[929412735] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"200.202697ms","start":"2026-02-11T02:23:25.609863Z","end":"2026-02-11T02:23:25.810066Z","steps":["trace[929412735] 'process raft request' (duration: 199.690265ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:26.432913Z","caller":"traceutil/trace.go:171","msg":"trace[233216272] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"239.511905ms","start":"2026-02-11T02:23:26.19338Z","end":"2026-02-11T02:23:26.432892Z","steps":["trace[233216272] 'read index received' (duration: 239.324997ms)","trace[233216272] 'applied index is now lower than readState.Index' (duration: 186.068µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:26.43318Z","caller":"traceutil/trace.go:171","msg":"trace[383876568] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"273.635481ms","start":"2026-02-11T02:23:26.159534Z","end":"2026-02-11T02:23:26.433169Z","steps":["trace[383876568] 'process raft request' (duration: 273.230184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:26.43342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.035718ms","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-11T02:23:26.433458Z","caller":"traceutil/trace.go:171","msg":"trace[1600647317] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:604; }","duration":"240.105071ms","start":"2026-02-11T02:23:26.193343Z","end":"2026-02-11T02:23:26.433448Z","steps":["trace[1600647317] 'agreement among raft nodes before linearized reading' (duration: 239.981656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:26.433609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.68374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:23:26.433641Z","caller":"traceutil/trace.go:171","msg":"trace[14174356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"159.775615ms","start":"2026-02-11T02:23:26.273856Z","end":"2026-02-11T02:23:26.433632Z","steps":["trace[14174356] 'agreement among raft nodes before linearized reading' (duration: 159.731553ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:26.684852Z","caller":"traceutil/trace.go:171","msg":"trace[446915535] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"150.102252ms","start":"2026-02-11T02:23:26.534737Z","end":"2026-02-11T02:23:26.684839Z","steps":["trace[446915535] 'process raft request' (duration: 149.740196ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:27.812107Z","caller":"traceutil/trace.go:171","msg":"trace[1226691064] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"101.917378ms","start":"2026-02-11T02:23:27.71016Z","end":"2026-02-11T02:23:27.812077Z","steps":["trace[1226691064] 'process raft request' (duration: 89.373189ms)","trace[1226691064] 'compare' (duration: 12.169263ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:32.523114Z","caller":"traceutil/trace.go:171","msg":"trace[1470632994] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"157.753256ms","start":"2026-02-11T02:23:32.365334Z","end":"2026-02-11T02:23:32.523087Z","steps":["trace[1470632994] 'process raft request' (duration: 146.549679ms)","trace[1470632994] 'compare' (duration: 10.874583ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:32.649206Z","caller":"traceutil/trace.go:171","msg":"trace[426556732] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"114.876591ms","start":"2026-02-11T02:23:32.534306Z","end":"2026-02-11T02:23:32.649183Z","steps":["trace[426556732] 'process raft request' (duration: 104.428188ms)","trace[426556732] 'compare' (duration: 10.350299ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:32.657447Z","caller":"traceutil/trace.go:171","msg":"trace[1590878013] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"119.421144ms","start":"2026-02-11T02:23:32.538011Z","end":"2026-02-11T02:23:32.657432Z","steps":["trace[1590878013] 'process raft request' (duration: 119.153522ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:23:41.987861Z","caller":"traceutil/trace.go:171","msg":"trace[1590162041] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"122.735859ms","start":"2026-02-11T02:23:41.86511Z","end":"2026-02-11T02:23:41.987846Z","steps":["trace[1590162041] 'read index received' (duration: 122.56214ms)","trace[1590162041] 'applied index is now lower than readState.Index' (duration: 173.319µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:23:41.987946Z","caller":"traceutil/trace.go:171","msg":"trace[487723680] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"270.448646ms","start":"2026-02-11T02:23:41.717492Z","end":"2026-02-11T02:23:41.98794Z","steps":["trace[487723680] 'process raft request' (duration: 270.216385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:23:41.988377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.254844ms","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-11T02:23:41.988409Z","caller":"traceutil/trace.go:171","msg":"trace[495019095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:740; }","duration":"123.324096ms","start":"2026-02-11T02:23:41.865072Z","end":"2026-02-11T02:23:41.988396Z","steps":["trace[495019095] 'agreement among raft nodes before linearized reading' (duration: 123.263233ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:24:27.520338Z","caller":"traceutil/trace.go:171","msg":"trace[1196044910] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"119.171019ms","start":"2026-02-11T02:24:27.401143Z","end":"2026-02-11T02:24:27.520314Z","steps":["trace[1196044910] 'process raft request' (duration: 119.017781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:24:45.201647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.996081ms","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-11T02:24:45.202173Z","caller":"traceutil/trace.go:171","msg":"trace[1244361294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"730.558892ms","start":"2026-02-11T02:24:44.471596Z","end":"2026-02-11T02:24:45.202155Z","steps":["trace[1244361294] 'range keys from in-memory index tree' (duration: 729.79428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:24:45.202233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:24:44.471581Z","time spent":"730.637337ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T02:24:45.202625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.861161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T02:24:45.202726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.997695ms","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-11T02:24:45.203049Z","caller":"traceutil/trace.go:171","msg":"trace[364056744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"695.413538ms","start":"2026-02-11T02:24:44.507621Z","end":"2026-02-11T02:24:45.203035Z","steps":["trace[364056744] 'range keys from in-memory index tree' (duration: 694.834565ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:24:45.202679Z","caller":"traceutil/trace.go:171","msg":"trace[112374403] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:960; }","duration":"230.948595ms","start":"2026-02-11T02:24:44.971717Z","end":"2026-02-11T02:24:45.202666Z","steps":["trace[112374403] 'range keys from in-memory index tree' (duration: 230.776185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:24:45.203251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:24:44.507604Z","time spent":"695.636641ms","remote":"127.0.0.1:33332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T02:24:45.339798Z","caller":"traceutil/trace.go:171","msg":"trace[1091306683] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"129.433549ms","start":"2026-02-11T02:24:45.210349Z","end":"2026-02-11T02:24:45.339783Z","steps":["trace[1091306683] 'process raft request' (duration: 129.345664ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:24:45.340072Z","caller":"traceutil/trace.go:171","msg":"trace[1643125388] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1003; }","duration":"129.298572ms","start":"2026-02-11T02:24:45.210746Z","end":"2026-02-11T02:24:45.340045Z","steps":["trace[1643125388] 'read index received' (duration: 129.289381ms)","trace[1643125388] 'applied index is now lower than readState.Index' (duration: 7.56µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:24:45.340569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.781829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:24:45.340688Z","caller":"traceutil/trace.go:171","msg":"trace[1248517082] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:961; }","duration":"129.922437ms","start":"2026-02-11T02:24:45.210728Z","end":"2026-02-11T02:24:45.340651Z","steps":["trace[1248517082] 'agreement among raft nodes before linearized reading' (duration: 129.548736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:24:45.362423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.626809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:24:45.362473Z","caller":"traceutil/trace.go:171","msg":"trace[101972355] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"151.656756ms","start":"2026-02-11T02:24:45.210802Z","end":"2026-02-11T02:24:45.362459Z","steps":["trace[101972355] 'process raft request' (duration: 151.366219ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:24:45.362509Z","caller":"traceutil/trace.go:171","msg":"trace[1162632185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"106.763827ms","start":"2026-02-11T02:24:45.255709Z","end":"2026-02-11T02:24:45.362473Z","steps":["trace[1162632185] 'agreement among raft nodes before linearized reading' (duration: 106.587947ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:24:45.362696Z","caller":"traceutil/trace.go:171","msg":"trace[239105956] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"150.437637ms","start":"2026-02-11T02:24:45.212252Z","end":"2026-02-11T02:24:45.362689Z","steps":["trace[239105956] 'process raft request' (duration: 150.014803ms)"],"step_count":1}