{"level":"warn","ts":"2026-02-19T13:21:13.741396Z","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-19T13:21:13.741496Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-19T13:21:13.741562Z","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-19T13:21:13.74157Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-19T13:21:13.741593Z","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-19T13:21:13.74224Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-02-19T13:21:13.742317Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-19T13:21:13.749135Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.625103ms"} {"level":"info","ts":"2026-02-19T13:21:13.759645Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-02-19T13:21:13.759747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T13:21:13.759775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-02-19T13:21:13.759789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T13:21:13.759796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-02-19T13:21:13.759834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-02-19T13:21:13.773972Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T13:21:13.776162Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T13:21:13.778407Z","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-19T13:21:13.781065Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T13:21:13.781567Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T13:21:13.781606Z","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-19T13:21:13.782908Z","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-19T13:21:13.783033Z","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-19T13:21:13.78798Z","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-19T13:21:13.78798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-02-19T13:21:13.788105Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-19T13:21:13.788246Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-19T13:21:13.788377Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T13:21:13.788416Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T13:21:13.788681Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-19T13:21:14.662132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T13:21:14.662216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T13:21:14.662253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-02-19T13:21:14.662282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-02-19T13:21:14.662301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-19T13:21:14.662361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-02-19T13:21:14.662378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-19T13:21:14.663633Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T13:21:14.66479Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T13:21:14.664793Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T13:21:14.66501Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T13:21:14.665322Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T13:21:14.665397Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T13:21:14.665425Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T13:21:14.665531Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T13:21:14.665568Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T13:21:14.670021Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-02-19T13:21:14.670131Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T13:21:18.789816Z","caller":"traceutil/trace.go:171","msg":"trace[1196505264] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:211; }","duration":"306.535554ms","start":"2026-02-19T13:21:18.483247Z","end":"2026-02-19T13:21:18.789783Z","steps":["trace[1196505264] 'read index received' (duration: 306.158774ms)","trace[1196505264] 'applied index is now lower than readState.Index' (duration: 374.92µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T13:21:18.789951Z","caller":"traceutil/trace.go:171","msg":"trace[680516207] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"526.307424ms","start":"2026-02-19T13:21:18.263596Z","end":"2026-02-19T13:21:18.789904Z","steps":["trace[680516207] 'process raft request' (duration: 525.906944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:21:18.790337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.051456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3181"} {"level":"info","ts":"2026-02-19T13:21:18.790509Z","caller":"traceutil/trace.go:171","msg":"trace[1847451798] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:207; }","duration":"307.277412ms","start":"2026-02-19T13:21:18.483174Z","end":"2026-02-19T13:21:18.790452Z","steps":["trace[1847451798] 'agreement among raft nodes before linearized reading' (duration: 306.77539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:21:18.790619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:21:18.483141Z","time spent":"307.458566ms","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3204,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-19T13:21:18.790731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:21:18.263578Z","time spent":"526.498369ms","remote":"127.0.0.1:40884","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-19T13:21:19.621105Z","caller":"traceutil/trace.go:171","msg":"trace[1709563001] linearizableReadLoop","detail":"{readStateIndex:215; appliedIndex:212; }","duration":"815.134424ms","start":"2026-02-19T13:21:18.805938Z","end":"2026-02-19T13:21:19.621073Z","steps":["trace[1709563001] 'read index received' (duration: 29.029981ms)","trace[1709563001] 'applied index is now lower than readState.Index' (duration: 786.103033ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T13:21:19.621182Z","caller":"traceutil/trace.go:171","msg":"trace[1576228988] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"821.862769ms","start":"2026-02-19T13:21:18.799285Z","end":"2026-02-19T13:21:19.621147Z","steps":["trace[1576228988] 'process raft request' (duration: 792.664284ms)","trace[1576228988] 'compare' (duration: 28.810605ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T13:21:19.622055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.069097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T13:21:19.622379Z","caller":"traceutil/trace.go:171","msg":"trace[1996456064] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:209; }","duration":"816.426276ms","start":"2026-02-19T13:21:18.805895Z","end":"2026-02-19T13:21:19.622321Z","steps":["trace[1996456064] 'agreement among raft nodes before linearized reading' (duration: 815.355071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:21:19.622757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:21:18.805858Z","time spent":"816.755985ms","remote":"127.0.0.1:40822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"warn","ts":"2026-02-19T13:21:19.624117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:21:18.799246Z","time spent":"822.919234ms","remote":"127.0.0.1:40884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T13:21:19.624694Z","caller":"traceutil/trace.go:171","msg":"trace[1531321562] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"821.978102ms","start":"2026-02-19T13:21:18.800904Z","end":"2026-02-19T13:21:19.622882Z","steps":["trace[1531321562] 'process raft request' (duration: 820.106196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:21:19.62482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:21:18.800888Z","time spent":"823.870478ms","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3384,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T13:21:19.627975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.379951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T13:21:19.628029Z","caller":"traceutil/trace.go:171","msg":"trace[2080018645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:210; }","duration":"159.485893ms","start":"2026-02-19T13:21:19.468522Z","end":"2026-02-19T13:21:19.628008Z","steps":["trace[2080018645] 'agreement among raft nodes before linearized reading' (duration: 159.272358ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T13:21:19.628185Z","caller":"traceutil/trace.go:171","msg":"trace[269109579] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"791.733632ms","start":"2026-02-19T13:21:18.836435Z","end":"2026-02-19T13:21:19.628169Z","steps":["trace[269109579] 'process raft request' (duration: 786.177616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:21:19.628247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:21:18.836399Z","time spent":"791.804604ms","remote":"127.0.0.1:40688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T13:21:43.993606Z","caller":"traceutil/trace.go:171","msg":"trace[1484653177] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"232.019456ms","start":"2026-02-19T13:21:43.761557Z","end":"2026-02-19T13:21:43.993576Z","steps":["trace[1484653177] 'read index received' (duration: 231.838183ms)","trace[1484653177] 'applied index is now lower than readState.Index' (duration: 180.283µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T13:21:43.99375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.166398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-19T13:21:43.993798Z","caller":"traceutil/trace.go:171","msg":"trace[1084460642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"232.26968ms","start":"2026-02-19T13:21:43.761518Z","end":"2026-02-19T13:21:43.993787Z","steps":["trace[1084460642] 'agreement among raft nodes before linearized reading' (duration: 232.172668ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T13:21:43.993852Z","caller":"traceutil/trace.go:171","msg":"trace[1849083914] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"256.273528ms","start":"2026-02-19T13:21:43.737545Z","end":"2026-02-19T13:21:43.993819Z","steps":["trace[1849083914] 'process raft request' (duration: 255.876461ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T13:21:52.133389Z","caller":"traceutil/trace.go:171","msg":"trace[1280969507] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"365.321088ms","start":"2026-02-19T13:21:51.768025Z","end":"2026-02-19T13:21:52.133346Z","steps":["trace[1280969507] 'process raft request' (duration: 365.094394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:21:52.133551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:21:51.767995Z","time spent":"365.475911ms","remote":"127.0.0.1:40806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T13:21:52.451973Z","caller":"traceutil/trace.go:171","msg":"trace[1642369473] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"129.623967ms","start":"2026-02-19T13:21:52.322324Z","end":"2026-02-19T13:21:52.451948Z","steps":["trace[1642369473] 'read index received' (duration: 93.055939ms)","trace[1642369473] 'applied index is now lower than readState.Index' (duration: 36.567228ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T13:21:52.452324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.954222ms","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-19T13:21:52.452222Z","caller":"traceutil/trace.go:171","msg":"trace[1667373668] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"230.305143ms","start":"2026-02-19T13:21:52.221883Z","end":"2026-02-19T13:21:52.452188Z","steps":["trace[1667373668] 'process raft request' (duration: 193.588203ms)","trace[1667373668] 'compare' (duration: 36.352263ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T13:21:52.452404Z","caller":"traceutil/trace.go:171","msg":"trace[213396029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:494; }","duration":"130.119815ms","start":"2026-02-19T13:21:52.322266Z","end":"2026-02-19T13:21:52.452385Z","steps":["trace[213396029] 'agreement among raft nodes before linearized reading' (duration: 129.784519ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T13:21:52.666817Z","caller":"traceutil/trace.go:171","msg":"trace[1058474259] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"210.44282ms","start":"2026-02-19T13:21:52.456316Z","end":"2026-02-19T13:21:52.666759Z","steps":["trace[1058474259] 'process raft request' (duration: 114.646752ms)","trace[1058474259] 'compare' (duration: 95.396391ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T13:21:53.863073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.940258ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T13:21:53.863218Z","caller":"traceutil/trace.go:171","msg":"trace[380274721] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"281.832505ms","start":"2026-02-19T13:21:53.581361Z","end":"2026-02-19T13:21:53.863194Z","steps":["trace[380274721] 'read index received' (duration: 45.692µs)","trace[380274721] 'applied index is now lower than readState.Index' (duration: 281.785013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T13:21:53.863425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.041159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T13:21:53.863562Z","caller":"traceutil/trace.go:171","msg":"trace[1426539533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"282.231262ms","start":"2026-02-19T13:21:53.581306Z","end":"2026-02-19T13:21:53.863537Z","steps":["trace[1426539533] 'agreement among raft nodes before linearized reading' (duration: 281.994708ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T13:22:06.910445Z","caller":"traceutil/trace.go:171","msg":"trace[1809144728] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"177.727031ms","start":"2026-02-19T13:22:06.732688Z","end":"2026-02-19T13:22:06.910415Z","steps":["trace[1809144728] 'process raft request' (duration: 177.577678ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T13:22:12.400623Z","caller":"traceutil/trace.go:171","msg":"trace[1536973448] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"103.099887ms","start":"2026-02-19T13:22:12.297506Z","end":"2026-02-19T13:22:12.400606Z","steps":["trace[1536973448] 'process raft request' (duration: 103.036834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:13.265925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.157022ms","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-19T13:22:13.266206Z","caller":"traceutil/trace.go:171","msg":"trace[685080517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:684; }","duration":"719.510628ms","start":"2026-02-19T13:22:12.546656Z","end":"2026-02-19T13:22:13.266166Z","steps":["trace[685080517] 'range keys from in-memory index tree' (duration: 718.827765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:13.266287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:12.546629Z","time spent":"719.640041ms","remote":"127.0.0.1:40884","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-19T13:22:13.845375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.226423ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T13:22:13.845489Z","caller":"traceutil/trace.go:171","msg":"trace[1062409772] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"903.843207ms","start":"2026-02-19T13:22:12.941626Z","end":"2026-02-19T13:22:13.845469Z","steps":["trace[1062409772] 'read index received' (duration: 386.574294ms)","trace[1062409772] 'applied index is now lower than readState.Index' (duration: 517.267133ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T13:22:13.846154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.080602ms","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-19T13:22:13.846204Z","caller":"traceutil/trace.go:171","msg":"trace[1523642225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:684; }","duration":"904.624403ms","start":"2026-02-19T13:22:12.941566Z","end":"2026-02-19T13:22:13.84619Z","steps":["trace[1523642225] 'agreement among raft nodes before linearized reading' (duration: 904.002971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:13.846243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:12.94154Z","time spent":"904.694903ms","remote":"127.0.0.1:40884","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":"warn","ts":"2026-02-19T13:22:13.888198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.384813ms","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-19T13:22:13.888412Z","caller":"traceutil/trace.go:171","msg":"trace[1727630512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:685; }","duration":"163.988284ms","start":"2026-02-19T13:22:13.724288Z","end":"2026-02-19T13:22:13.888276Z","steps":["trace[1727630512] 'agreement among raft nodes before linearized reading' (duration: 163.332822ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T13:22:13.887778Z","caller":"traceutil/trace.go:171","msg":"trace[1816038115] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"615.492095ms","start":"2026-02-19T13:22:13.272141Z","end":"2026-02-19T13:22:13.887634Z","steps":["trace[1816038115] 'process raft request' (duration: 615.295821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:13.888564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:13.272121Z","time spent":"616.353462ms","remote":"127.0.0.1:40884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T13:22:13.888739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.84368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T13:22:13.888774Z","caller":"traceutil/trace.go:171","msg":"trace[864904207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"306.938061ms","start":"2026-02-19T13:22:13.581821Z","end":"2026-02-19T13:22:13.888759Z","steps":["trace[864904207] 'agreement among raft nodes before linearized reading' (duration: 305.77404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:13.888813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:13.581795Z","time spent":"307.010373ms","remote":"127.0.0.1:40600","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-19T13:22:14.905168Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674857775605447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T13:22:15.368081Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.004266411s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T13:22:15.368502Z","caller":"traceutil/trace.go:171","msg":"trace[948609092] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"963.92516ms","start":"2026-02-19T13:22:14.404548Z","end":"2026-02-19T13:22:15.368473Z","steps":["trace[948609092] 'read index received' (duration: 963.677145ms)","trace[948609092] 'applied index is now lower than readState.Index' (duration: 246.855µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T13:22:15.368642Z","caller":"traceutil/trace.go:171","msg":"trace[1596156543] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"1.004894623s","start":"2026-02-19T13:22:14.363699Z","end":"2026-02-19T13:22:15.368594Z","steps":["trace[1596156543] 'process raft request' (duration: 1.004614018s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:15.368728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.168304ms","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-19T13:22:15.368778Z","caller":"traceutil/trace.go:171","msg":"trace[1008250851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:688; }","duration":"964.268456ms","start":"2026-02-19T13:22:14.404492Z","end":"2026-02-19T13:22:15.36876Z","steps":["trace[1008250851] 'agreement among raft nodes before linearized reading' (duration: 964.081403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:15.368822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:14.404466Z","time spent":"964.348237ms","remote":"127.0.0.1:40884","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-19T13:22:15.368868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:14.363666Z","time spent":"1.005074086s","remote":"127.0.0.1:40884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T13:22:15.621474Z","caller":"traceutil/trace.go:171","msg":"trace[392793992] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"247.277568ms","start":"2026-02-19T13:22:15.374157Z","end":"2026-02-19T13:22:15.621434Z","steps":["trace[392793992] 'process raft request' (duration: 232.024022ms)","trace[392793992] 'compare' (duration: 15.038672ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T13:22:16.101748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.742196ms","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-19T13:22:16.101823Z","caller":"traceutil/trace.go:171","msg":"trace[927287538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:689; }","duration":"185.862048ms","start":"2026-02-19T13:22:15.915942Z","end":"2026-02-19T13:22:16.101804Z","steps":["trace[927287538] 'range keys from in-memory index tree' (duration: 185.582783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:16.102219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.771853ms","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-19T13:22:16.102347Z","caller":"traceutil/trace.go:171","msg":"trace[837048080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:689; }","duration":"205.974467ms","start":"2026-02-19T13:22:15.896347Z","end":"2026-02-19T13:22:16.102321Z","steps":["trace[837048080] 'range keys from in-memory index tree' (duration: 205.514618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:16.102911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.106876ms","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-19T13:22:16.103299Z","caller":"traceutil/trace.go:171","msg":"trace[2012153936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:689; }","duration":"196.599596ms","start":"2026-02-19T13:22:15.906633Z","end":"2026-02-19T13:22:16.103233Z","steps":["trace[2012153936] 'range keys from in-memory index tree' (duration: 194.906493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:39.888299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.944436ms","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-19T13:22:39.888435Z","caller":"traceutil/trace.go:171","msg":"trace[1007753271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"114.1402ms","start":"2026-02-19T13:22:39.774261Z","end":"2026-02-19T13:22:39.888401Z","steps":["trace[1007753271] 'range keys from in-memory index tree' (duration: 113.701692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:39.888584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.030524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T13:22:39.888726Z","caller":"traceutil/trace.go:171","msg":"trace[727844003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"308.226166ms","start":"2026-02-19T13:22:39.580442Z","end":"2026-02-19T13:22:39.888669Z","steps":["trace[727844003] 'range keys from in-memory index tree' (duration: 307.939321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:39.888809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:39.58042Z","time spent":"308.37738ms","remote":"127.0.0.1:40600","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-19T13:22:39.888789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.644677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T13:22:39.888879Z","caller":"traceutil/trace.go:171","msg":"trace[2017102633] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:762; }","duration":"306.803789ms","start":"2026-02-19T13:22:39.582055Z","end":"2026-02-19T13:22:39.888859Z","steps":["trace[2017102633] 'count revisions from in-memory index tree' (duration: 306.546334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:39.889005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:39.582032Z","time spent":"306.959552ms","remote":"127.0.0.1:40782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-02-19T13:22:40.132618Z","caller":"traceutil/trace.go:171","msg":"trace[160389989] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"238.390785ms","start":"2026-02-19T13:22:39.894196Z","end":"2026-02-19T13:22:40.132587Z","steps":["trace[160389989] 'process raft request' (duration: 238.212941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:40.84112Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674857775605673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T13:22:41.342272Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674857775605673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T13:22:41.842972Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674857775605673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T13:22:42.343717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674857775605673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T13:22:42.450447Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.119107694s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T13:22:42.450983Z","caller":"traceutil/trace.go:171","msg":"trace[972461923] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"2.119779567s","start":"2026-02-19T13:22:40.331177Z","end":"2026-02-19T13:22:42.450957Z","steps":["trace[972461923] 'process raft request' (duration: 2.119526802s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:42.451229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:40.33114Z","time spent":"2.119924909s","remote":"127.0.0.1:40884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T13:22:42.472287Z","caller":"traceutil/trace.go:171","msg":"trace[2024883732] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:794; }","duration":"2.131190016s","start":"2026-02-19T13:22:40.341058Z","end":"2026-02-19T13:22:42.472247Z","steps":["trace[2024883732] 'read index received' (duration: 2.109587149s)","trace[2024883732] 'applied index is now lower than readState.Index' (duration: 21.601337ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T13:22:42.47237Z","caller":"traceutil/trace.go:171","msg":"trace[703251743] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"353.721567ms","start":"2026-02-19T13:22:42.118622Z","end":"2026-02-19T13:22:42.472343Z","steps":["trace[703251743] 'process raft request' (duration: 353.3429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:42.472587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:42.118589Z","time spent":"353.860319ms","remote":"127.0.0.1:40884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T13:22:42.472589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.27923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T13:22:42.472619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.131540244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-19T13:22:42.472663Z","caller":"traceutil/trace.go:171","msg":"trace[1882415501] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:766; }","duration":"179.439054ms","start":"2026-02-19T13:22:42.293206Z","end":"2026-02-19T13:22:42.472645Z","steps":["trace[1882415501] 'agreement among raft nodes before linearized reading' (duration: 179.310061ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T13:22:42.472683Z","caller":"traceutil/trace.go:171","msg":"trace[1879555986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"2.131634065s","start":"2026-02-19T13:22:40.341027Z","end":"2026-02-19T13:22:42.472661Z","steps":["trace[1879555986] 'agreement among raft nodes before linearized reading' (duration: 2.131406871s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:42.472718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.58677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T13:22:42.472741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:40.341015Z","time spent":"2.131716967s","remote":"127.0.0.1:40884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-19T13:22:42.472762Z","caller":"traceutil/trace.go:171","msg":"trace[719170135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"891.644351ms","start":"2026-02-19T13:22:41.581105Z","end":"2026-02-19T13:22:42.472749Z","steps":["trace[719170135] 'agreement among raft nodes before linearized reading' (duration: 891.59413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:42.472812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:41.581094Z","time spent":"891.709043ms","remote":"127.0.0.1:40600","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-19T13:22:42.4728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.874972ms","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-19T13:22:42.472886Z","caller":"traceutil/trace.go:171","msg":"trace[429364754] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"336.009135ms","start":"2026-02-19T13:22:42.136854Z","end":"2026-02-19T13:22:42.472863Z","steps":["trace[429364754] 'agreement among raft nodes before linearized reading' (duration: 335.717748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:42.472981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.202979ms","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-19T13:22:42.473028Z","caller":"traceutil/trace.go:171","msg":"trace[777464121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"150.30044ms","start":"2026-02-19T13:22:42.322716Z","end":"2026-02-19T13:22:42.473016Z","steps":["trace[777464121] 'agreement among raft nodes before linearized reading' (duration: 150.127726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:42.472669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.137904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-19T13:22:42.47317Z","caller":"traceutil/trace.go:171","msg":"trace[769023314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"978.694166ms","start":"2026-02-19T13:22:41.494433Z","end":"2026-02-19T13:22:42.473128Z","steps":["trace[769023314] 'agreement among raft nodes before linearized reading' (duration: 978.021892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:42.47326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:41.494401Z","time spent":"978.804168ms","remote":"127.0.0.1:40884","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":"warn","ts":"2026-02-19T13:22:42.472986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:42.136841Z","time spent":"336.135247ms","remote":"127.0.0.1:40884","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-19T13:22:57.067311Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674857775605877,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T13:22:57.175201Z","caller":"traceutil/trace.go:171","msg":"trace[299547548] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"609.443688ms","start":"2026-02-19T13:22:56.56573Z","end":"2026-02-19T13:22:57.175174Z","steps":["trace[299547548] 'process raft request' (duration: 609.256304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:57.17542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:56.565711Z","time spent":"609.58359ms","remote":"127.0.0.1:40884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T13:22:57.203167Z","caller":"traceutil/trace.go:171","msg":"trace[1140099568] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:889; }","duration":"636.92769ms","start":"2026-02-19T13:22:56.566199Z","end":"2026-02-19T13:22:57.203126Z","steps":["trace[1140099568] 'read index received' (duration: 608.762675ms)","trace[1140099568] 'applied index is now lower than readState.Index' (duration: 28.163595ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T13:22:57.203339Z","caller":"traceutil/trace.go:171","msg":"trace[435785923] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"634.542014ms","start":"2026-02-19T13:22:56.568784Z","end":"2026-02-19T13:22:57.203326Z","steps":["trace[435785923] 'process raft request' (duration: 634.139286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:57.20352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:56.568754Z","time spent":"634.621855ms","remote":"127.0.0.1:40884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T13:22:57.203547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.551703ms","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-19T13:22:57.203636Z","caller":"traceutil/trace.go:171","msg":"trace[1144744643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:856; }","duration":"636.699796ms","start":"2026-02-19T13:22:56.566907Z","end":"2026-02-19T13:22:57.203607Z","steps":["trace[1144744643] 'agreement among raft nodes before linearized reading' (duration: 636.489361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:57.203678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:56.566873Z","time spent":"636.796578ms","remote":"127.0.0.1:40884","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-19T13:22:57.203838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.637874ms","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-19T13:22:57.203885Z","caller":"traceutil/trace.go:171","msg":"trace[329539413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:856; }","duration":"637.722276ms","start":"2026-02-19T13:22:56.566147Z","end":"2026-02-19T13:22:57.203869Z","steps":["trace[329539413] 'agreement among raft nodes before linearized reading' (duration: 637.549912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:57.203968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:56.566114Z","time spent":"637.845968ms","remote":"127.0.0.1:40884","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-19T13:22:57.204084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.432238ms","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-19T13:22:57.204184Z","caller":"traceutil/trace.go:171","msg":"trace[736803285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:856; }","duration":"624.577041ms","start":"2026-02-19T13:22:56.579585Z","end":"2026-02-19T13:22:57.204162Z","steps":["trace[736803285] 'agreement among raft nodes before linearized reading' (duration: 624.226795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:22:57.204234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:22:56.579575Z","time spent":"624.650342ms","remote":"127.0.0.1:40884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-19T13:23:16.116731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.326208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T13:23:16.116886Z","caller":"traceutil/trace.go:171","msg":"trace[223657513] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"535.306168ms","start":"2026-02-19T13:23:15.58156Z","end":"2026-02-19T13:23:16.116866Z","steps":["trace[223657513] 'process raft request' (duration: 322.763487ms)","trace[223657513] 'compare' (duration: 211.97625ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T13:23:16.116975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:23:15.581546Z","time spent":"535.405329ms","remote":"127.0.0.1:40688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T13:23:16.403954Z","caller":"traceutil/trace.go:171","msg":"trace[946989854] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:972; }","duration":"526.249668ms","start":"2026-02-19T13:23:15.877683Z","end":"2026-02-19T13:23:16.403933Z","steps":["trace[946989854] 'read index received' (duration: 26.663117ms)","trace[946989854] 'applied index is now lower than readState.Index' (duration: 499.585191ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T13:23:16.404054Z","caller":"traceutil/trace.go:171","msg":"trace[290759125] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"636.669036ms","start":"2026-02-19T13:23:15.767378Z","end":"2026-02-19T13:23:16.404047Z","steps":["trace[290759125] 'process raft request' (duration: 636.514963ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T13:23:16.404042Z","caller":"traceutil/trace.go:171","msg":"trace[32623037] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"818.182569ms","start":"2026-02-19T13:23:15.585813Z","end":"2026-02-19T13:23:16.403996Z","steps":["trace[32623037] 'process raft request' (duration: 817.916115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:23:16.404129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:23:15.767339Z","time spent":"636.731238ms","remote":"127.0.0.1:40688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T13:23:16.404213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:23:15.585805Z","time spent":"818.315493ms","remote":"127.0.0.1:40884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T13:23:16.40439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.681307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T13:23:16.404475Z","caller":"traceutil/trace.go:171","msg":"trace[734161377] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:936; }","duration":"526.847639ms","start":"2026-02-19T13:23:15.877603Z","end":"2026-02-19T13:23:16.404451Z","steps":["trace[734161377] 'agreement among raft nodes before linearized reading' (duration: 526.573085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:23:16.404522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:23:15.877561Z","time spent":"526.951361ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":103,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-19T13:23:16.404706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.12996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T13:23:16.404746Z","caller":"traceutil/trace.go:171","msg":"trace[1685635260] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:936; }","duration":"325.229062ms","start":"2026-02-19T13:23:16.079505Z","end":"2026-02-19T13:23:16.404734Z","steps":["trace[1685635260] 'agreement among raft nodes before linearized reading' (duration: 324.5948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:23:16.404788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:23:16.079479Z","time spent":"325.300442ms","remote":"127.0.0.1:41166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-02-19T13:23:17.406059Z","caller":"traceutil/trace.go:171","msg":"trace[1800849926] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"111.566648ms","start":"2026-02-19T13:23:17.294146Z","end":"2026-02-19T13:23:17.405712Z","steps":["trace[1800849926] 'process raft request' (duration: 111.272343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:23:17.636509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.384007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T13:23:17.636869Z","caller":"traceutil/trace.go:171","msg":"trace[636306132] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"222.362784ms","start":"2026-02-19T13:23:17.414482Z","end":"2026-02-19T13:23:17.636845Z","steps":["trace[636306132] 'process raft request' (duration: 222.190111ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T13:23:17.636859Z","caller":"traceutil/trace.go:171","msg":"trace[686500876] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"222.333883ms","start":"2026-02-19T13:23:17.414478Z","end":"2026-02-19T13:23:17.636812Z","steps":["trace[686500876] 'process raft request' (duration: 44.519939ms)","trace[686500876] 'compare' (duration: 177.059361ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T13:23:17.637094Z","caller":"traceutil/trace.go:171","msg":"trace[1085901912] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"221.640591ms","start":"2026-02-19T13:23:17.415441Z","end":"2026-02-19T13:23:17.637082Z","steps":["trace[1085901912] 'process raft request' (duration: 221.349365ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T13:23:26.706052Z","caller":"traceutil/trace.go:171","msg":"trace[650407599] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"283.186119ms","start":"2026-02-19T13:23:26.422822Z","end":"2026-02-19T13:23:26.706009Z","steps":["trace[650407599] 'read index received' (duration: 282.885764ms)","trace[650407599] 'applied index is now lower than readState.Index' (duration: 299.235µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T13:23:26.706112Z","caller":"traceutil/trace.go:171","msg":"trace[256218810] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"510.459854ms","start":"2026-02-19T13:23:26.195597Z","end":"2026-02-19T13:23:26.706057Z","steps":["trace[256218810] 'process raft request' (duration: 510.158609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T13:23:26.706393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T13:23:26.195575Z","time spent":"510.646818ms","remote":"127.0.0.1:40806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13250,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T13:23:26.706376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.512895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-19T13:23:26.706505Z","caller":"traceutil/trace.go:171","msg":"trace[837848972] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:997; }","duration":"283.741899ms","start":"2026-02-19T13:23:26.422743Z","end":"2026-02-19T13:23:26.706485Z","steps":["trace[837848972] 'agreement among raft nodes before linearized reading' (duration: 283.377912ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T13:26:34.609926Z","caller":"traceutil/trace.go:171","msg":"trace[510633754] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1876; }","duration":"128.92668ms","start":"2026-02-19T13:26:34.480972Z","end":"2026-02-19T13:26:34.609898Z","steps":["trace[510633754] 'read index received' (duration: 54.697552ms)","trace[510633754] 'applied index is now lower than readState.Index' (duration: 74.227978ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T13:26:34.610072Z","caller":"traceutil/trace.go:171","msg":"trace[1170657154] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"129.250755ms","start":"2026-02-19T13:26:34.480781Z","end":"2026-02-19T13:26:34.610031Z","steps":["trace[1170657154] 'process raft request' (duration: 92.936693ms)","trace[1170657154] 'compare' (duration: 36.102089ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T13:26:34.610331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.345286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-19T13:26:34.610465Z","caller":"traceutil/trace.go:171","msg":"trace[468264944] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1798; }","duration":"129.55747ms","start":"2026-02-19T13:26:34.480869Z","end":"2026-02-19T13:26:34.610426Z","steps":["trace[468264944] 'agreement among raft nodes before linearized reading' (duration: 129.237994ms)"],"step_count":1}