{"level":"warn","ts":"2026-02-20T10:05:05.870762Z","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-20T10:05:05.870864Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.204.45.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-20T10:05:05.870957Z","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-20T10:05:05.870972Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-02-20T10:05:05.871009Z","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-20T10:05:05.871738Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-02-20T10:05:05.871832Z","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://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-20T10:05:05.88044Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.259991ms"} {"level":"info","ts":"2026-02-20T10:05:05.896509Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-02-20T10:05:05.896594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T10:05:05.896617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-02-20T10:05:05.896651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T10:05:05.896666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-02-20T10:05:05.896755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-02-20T10:05:05.905961Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T10:05:05.908853Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T10:05:05.925405Z","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-20T10:05:05.928818Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T10:05:05.929077Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T10:05:05.929299Z","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-20T10:05:05.930322Z","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-20T10:05:05.930492Z","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-20T10:05:05.933766Z","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-20T10:05:05.934018Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T10:05:05.934062Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T10:05:05.934232Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-02-20T10:05:05.934307Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-02-20T10:05:05.934382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-02-20T10:05:05.934576Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-02-20T10:05:06.797612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T10:05:06.797683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T10:05:06.797724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-02-20T10:05:06.797745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-02-20T10:05:06.797758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-02-20T10:05:06.797781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-02-20T10:05:06.797797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-02-20T10:05:06.844851Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T10:05:06.850248Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T10:05:06.850256Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T10:05:06.850304Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T10:05:06.850676Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T10:05:06.850779Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T10:05:06.854308Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-02-20T10:05:06.855337Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-20T10:05:06.857912Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T10:05:06.858343Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T10:05:06.858469Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T10:05:08.330437Z","caller":"traceutil/trace.go:171","msg":"trace[424841094] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"196.66897ms","start":"2026-02-20T10:05:08.133732Z","end":"2026-02-20T10:05:08.330401Z","steps":["trace[424841094] 'read index received' (duration: 62.130052ms)","trace[424841094] 'applied index is now lower than readState.Index' (duration: 134.537378ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:05:08.330459Z","caller":"traceutil/trace.go:171","msg":"trace[1279925757] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"197.560078ms","start":"2026-02-20T10:05:08.132866Z","end":"2026-02-20T10:05:08.330427Z","steps":["trace[1279925757] 'process raft request' (duration: 105.18124ms)","trace[1279925757] 'compare' (duration: 92.184572ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:05:08.330699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.896387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-20T10:05:08.330823Z","caller":"traceutil/trace.go:171","msg":"trace[1864773945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3; }","duration":"197.076973ms","start":"2026-02-20T10:05:08.133718Z","end":"2026-02-20T10:05:08.330795Z","steps":["trace[1864773945] 'agreement among raft nodes before linearized reading' (duration: 196.771204ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:08.335284Z","caller":"traceutil/trace.go:171","msg":"trace[909785591] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"112.514464ms","start":"2026-02-20T10:05:08.222732Z","end":"2026-02-20T10:05:08.335247Z","steps":["trace[909785591] 'process raft request' (duration: 112.303757ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:08.335336Z","caller":"traceutil/trace.go:171","msg":"trace[1523271498] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"101.951621ms","start":"2026-02-20T10:05:08.233365Z","end":"2026-02-20T10:05:08.335316Z","steps":["trace[1523271498] 'process raft request' (duration: 101.836547ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:08.335351Z","caller":"traceutil/trace.go:171","msg":"trace[811270921] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"106.327304ms","start":"2026-02-20T10:05:08.228993Z","end":"2026-02-20T10:05:08.33532Z","steps":["trace[811270921] 'process raft request' (duration: 106.172479ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:08.335361Z","caller":"traceutil/trace.go:171","msg":"trace[1378214931] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"101.749605ms","start":"2026-02-20T10:05:08.233591Z","end":"2026-02-20T10:05:08.33534Z","steps":["trace[1378214931] 'process raft request' (duration: 101.639371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:08.335401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.238339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-20T10:05:08.335744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.893096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-20T10:05:08.335777Z","caller":"traceutil/trace.go:171","msg":"trace[932015047] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"113.962538ms","start":"2026-02-20T10:05:08.221807Z","end":"2026-02-20T10:05:08.33577Z","steps":["trace[932015047] 'agreement among raft nodes before linearized reading' (duration: 113.913756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:08.33579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.996808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-20T10:05:08.335852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.468713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-20T10:05:08.335878Z","caller":"traceutil/trace.go:171","msg":"trace[583796194] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:0; response_revision:11; }","duration":"114.548316ms","start":"2026-02-20T10:05:08.221323Z","end":"2026-02-20T10:05:08.335871Z","steps":["trace[583796194] 'agreement among raft nodes before linearized reading' (duration: 114.512285ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:08.335881Z","caller":"traceutil/trace.go:171","msg":"trace[47718858] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"113.145303ms","start":"2026-02-20T10:05:08.222718Z","end":"2026-02-20T10:05:08.335863Z","steps":["trace[47718858] 'agreement among raft nodes before linearized reading' (duration: 112.974567ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:08.336275Z","caller":"traceutil/trace.go:171","msg":"trace[1864322850] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"201.438826ms","start":"2026-02-20T10:05:08.134145Z","end":"2026-02-20T10:05:08.335583Z","steps":["trace[1864322850] 'agreement among raft nodes before linearized reading' (duration: 201.212959ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:08.335441Z","caller":"traceutil/trace.go:171","msg":"trace[865167981] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"101.114056ms","start":"2026-02-20T10:05:08.234318Z","end":"2026-02-20T10:05:08.335432Z","steps":["trace[865167981] 'process raft request' (duration: 100.93351ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:08.335465Z","caller":"traceutil/trace.go:171","msg":"trace[675729888] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"101.048883ms","start":"2026-02-20T10:05:08.234408Z","end":"2026-02-20T10:05:08.335457Z","steps":["trace[675729888] 'process raft request' (duration: 100.866788ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:08.335482Z","caller":"traceutil/trace.go:171","msg":"trace[1077708596] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"100.61969ms","start":"2026-02-20T10:05:08.234858Z","end":"2026-02-20T10:05:08.335477Z","steps":["trace[1077708596] 'process raft request' (duration: 100.437644ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:08.335504Z","caller":"traceutil/trace.go:171","msg":"trace[1103886981] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"100.62475ms","start":"2026-02-20T10:05:08.234871Z","end":"2026-02-20T10:05:08.335496Z","steps":["trace[1103886981] 'process raft request' (duration: 100.444925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:13.496198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.179075ms","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-20T10:05:13.496289Z","caller":"traceutil/trace.go:171","msg":"trace[2098127538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:315; }","duration":"137.32698ms","start":"2026-02-20T10:05:13.358946Z","end":"2026-02-20T10:05:13.496273Z","steps":["trace[2098127538] 'range keys from in-memory index tree' (duration: 137.036441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:13.496434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.640974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-20T10:05:13.496589Z","caller":"traceutil/trace.go:171","msg":"trace[1520634143] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:315; }","duration":"307.861521ms","start":"2026-02-20T10:05:13.188692Z","end":"2026-02-20T10:05:13.496553Z","steps":["trace[1520634143] 'range keys from in-memory index tree' (duration: 307.491889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:13.496666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:13.188669Z","time spent":"307.981205ms","remote":"127.0.0.1:34776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-20T10:05:19.1963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.429701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T10:05:19.196447Z","caller":"traceutil/trace.go:171","msg":"trace[1793174147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:399; }","duration":"362.631888ms","start":"2026-02-20T10:05:18.83379Z","end":"2026-02-20T10:05:19.196422Z","steps":["trace[1793174147] 'range keys from in-memory index tree' (duration: 362.300346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:19.1965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:18.833772Z","time spent":"362.718781ms","remote":"127.0.0.1:34928","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":"info","ts":"2026-02-20T10:05:19.196443Z","caller":"traceutil/trace.go:171","msg":"trace[1004522265] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"344.241896ms","start":"2026-02-20T10:05:18.852161Z","end":"2026-02-20T10:05:19.196402Z","steps":["trace[1004522265] 'process raft request' (duration: 341.53475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:19.197628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:18.852142Z","time spent":"344.718703ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:05:24.828368Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289644967291809,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:05:25.329008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289644967291809,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:05:25.458211Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.418068277s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T10:05:25.458693Z","caller":"traceutil/trace.go:171","msg":"trace[1773151287] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"1.130347626s","start":"2026-02-20T10:05:24.328308Z","end":"2026-02-20T10:05:25.458656Z","steps":["trace[1773151287] 'read index received' (duration: 1.130287833s)","trace[1773151287] 'applied index is now lower than readState.Index' (duration: 58.043µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:05:25.458783Z","caller":"traceutil/trace.go:171","msg":"trace[1670677105] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"1.418659169s","start":"2026-02-20T10:05:24.040073Z","end":"2026-02-20T10:05:25.458732Z","steps":["trace[1670677105] 'process raft request' (duration: 1.418394599s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:25.458985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130648707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"warn","ts":"2026-02-20T10:05:25.459034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:24.040046Z","time spent":"1.418869427s","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:05:25.459079Z","caller":"traceutil/trace.go:171","msg":"trace[1108095057] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:420; }","duration":"1.130793043s","start":"2026-02-20T10:05:24.328264Z","end":"2026-02-20T10:05:25.459058Z","steps":["trace[1108095057] 'agreement among raft nodes before linearized reading' (duration: 1.130541683s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:25.459127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:24.328241Z","time spent":"1.130874796s","remote":"127.0.0.1:34848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6920,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-20T10:05:25.670655Z","caller":"traceutil/trace.go:171","msg":"trace[1534415140] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"200.995568ms","start":"2026-02-20T10:05:25.469632Z","end":"2026-02-20T10:05:25.670627Z","steps":["trace[1534415140] 'process raft request' (duration: 200.893914ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:25.670681Z","caller":"traceutil/trace.go:171","msg":"trace[814285113] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"211.771972ms","start":"2026-02-20T10:05:25.458863Z","end":"2026-02-20T10:05:25.670635Z","steps":["trace[814285113] 'read index received' (duration: 177.848908ms)","trace[814285113] 'applied index is now lower than readState.Index' (duration: 33.917154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:05:25.671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.136692ms","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-20T10:05:25.67106Z","caller":"traceutil/trace.go:171","msg":"trace[500610802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"406.334779ms","start":"2026-02-20T10:05:25.264714Z","end":"2026-02-20T10:05:25.671048Z","steps":["trace[500610802] 'agreement among raft nodes before linearized reading' (duration: 406.08694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:25.671104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:25.26469Z","time spent":"406.404572ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T10:05:25.671203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105083792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T10:05:25.671394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.147532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-20T10:05:25.671491Z","caller":"traceutil/trace.go:171","msg":"trace[758307698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"1.105373573s","start":"2026-02-20T10:05:24.566093Z","end":"2026-02-20T10:05:25.671466Z","steps":["trace[758307698] 'agreement among raft nodes before linearized reading' (duration: 1.105057671s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:25.671544Z","caller":"traceutil/trace.go:171","msg":"trace[1183073255] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:421; }","duration":"508.377271ms","start":"2026-02-20T10:05:25.163144Z","end":"2026-02-20T10:05:25.671521Z","steps":["trace[1183073255] 'agreement among raft nodes before linearized reading' (duration: 508.07616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:25.671574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:24.566035Z","time spent":"1.105509998s","remote":"127.0.0.1:34928","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-20T10:05:25.671602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:25.163119Z","time spent":"508.472355ms","remote":"127.0.0.1:35122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4056,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-20T10:05:25.671238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.750589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:05:25.671679Z","caller":"traceutil/trace.go:171","msg":"trace[264685718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"589.231828ms","start":"2026-02-20T10:05:25.082436Z","end":"2026-02-20T10:05:25.671667Z","steps":["trace[264685718] 'agreement among raft nodes before linearized reading' (duration: 588.787571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:25.671716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:25.082411Z","time spent":"589.29625ms","remote":"127.0.0.1:34668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T10:05:26.318806Z","caller":"traceutil/trace.go:171","msg":"trace[972992404] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:469; }","duration":"234.78815ms","start":"2026-02-20T10:05:26.083987Z","end":"2026-02-20T10:05:26.318775Z","steps":["trace[972992404] 'read index received' (duration: 234.563991ms)","trace[972992404] 'applied index is now lower than readState.Index' (duration: 222.339µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:05:26.31895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.946956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:05:26.319015Z","caller":"traceutil/trace.go:171","msg":"trace[2144679427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"235.084261ms","start":"2026-02-20T10:05:26.083922Z","end":"2026-02-20T10:05:26.319006Z","steps":["trace[2144679427] 'agreement among raft nodes before linearized reading' (duration: 234.947066ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:26.319269Z","caller":"traceutil/trace.go:171","msg":"trace[1970014708] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"402.558298ms","start":"2026-02-20T10:05:25.916262Z","end":"2026-02-20T10:05:26.318821Z","steps":["trace[1970014708] 'process raft request' (duration: 402.305979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:26.319358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:25.916201Z","time spent":"403.090138ms","remote":"127.0.0.1:35122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:05:31.805735Z","caller":"traceutil/trace.go:171","msg":"trace[1240367506] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"319.299331ms","start":"2026-02-20T10:05:31.486396Z","end":"2026-02-20T10:05:31.805695Z","steps":["trace[1240367506] 'process raft request' (duration: 318.969858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:31.805907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:31.486378Z","time spent":"319.439787ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:05:40.049302Z","caller":"traceutil/trace.go:171","msg":"trace[568142752] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"172.561716ms","start":"2026-02-20T10:05:39.876727Z","end":"2026-02-20T10:05:40.049289Z","steps":["trace[568142752] 'process raft request' (duration: 83.961879ms)","trace[568142752] 'compare' (duration: 88.44562ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:05:40.049569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.881215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T10:05:40.049651Z","caller":"traceutil/trace.go:171","msg":"trace[654205007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"166.01279ms","start":"2026-02-20T10:05:39.88362Z","end":"2026-02-20T10:05:40.049633Z","steps":["trace[654205007] 'agreement among raft nodes before linearized reading' (duration: 165.791481ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:40.049828Z","caller":"traceutil/trace.go:171","msg":"trace[561405817] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"124.025835ms","start":"2026-02-20T10:05:39.925237Z","end":"2026-02-20T10:05:40.049263Z","steps":["trace[561405817] 'read index received' (duration: 35.568765ms)","trace[561405817] 'applied index is now lower than readState.Index' (duration: 88.45601ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:05:40.119656Z","caller":"traceutil/trace.go:171","msg":"trace[1949481944] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"190.034149ms","start":"2026-02-20T10:05:39.929536Z","end":"2026-02-20T10:05:40.119571Z","steps":["trace[1949481944] 'process raft request' (duration: 189.787519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:42.220238Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.728149062s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T10:05:42.220639Z","caller":"traceutil/trace.go:171","msg":"trace[17646672] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"291.542906ms","start":"2026-02-20T10:05:41.929042Z","end":"2026-02-20T10:05:42.220585Z","steps":["trace[17646672] 'read index received' (duration: 291.394839ms)","trace[17646672] 'applied index is now lower than readState.Index' (duration: 147.227µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:05:42.220866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.806877ms","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-20T10:05:42.220813Z","caller":"traceutil/trace.go:171","msg":"trace[659323215] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.72879104s","start":"2026-02-20T10:05:40.491955Z","end":"2026-02-20T10:05:42.220746Z","steps":["trace[659323215] 'process raft request' (duration: 1.728505227s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:42.220894Z","caller":"traceutil/trace.go:171","msg":"trace[1571841582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"291.889751ms","start":"2026-02-20T10:05:41.928995Z","end":"2026-02-20T10:05:42.220885Z","steps":["trace[1571841582] 'agreement among raft nodes before linearized reading' (duration: 291.739424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:42.221012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.196297ms","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-20T10:05:42.221072Z","caller":"traceutil/trace.go:171","msg":"trace[2132861399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"167.318312ms","start":"2026-02-20T10:05:42.053725Z","end":"2026-02-20T10:05:42.221044Z","steps":["trace[2132861399] 'agreement among raft nodes before linearized reading' (duration: 167.063911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:42.221078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:40.491941Z","time spent":"1.728978977s","remote":"127.0.0.1:34836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:05:42.22117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.231572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:05:42.221195Z","caller":"traceutil/trace.go:171","msg":"trace[1363534775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"140.291164ms","start":"2026-02-20T10:05:42.080898Z","end":"2026-02-20T10:05:42.221189Z","steps":["trace[1363534775] 'agreement among raft nodes before linearized reading' (duration: 140.254122ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:42.819464Z","caller":"traceutil/trace.go:171","msg":"trace[95009959] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"593.22163ms","start":"2026-02-20T10:05:42.226218Z","end":"2026-02-20T10:05:42.81944Z","steps":["trace[95009959] 'process raft request' (duration: 553.373332ms)","trace[95009959] 'compare' (duration: 39.742693ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:05:42.820262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:42.226191Z","time spent":"593.902639ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:05:42.89287Z","caller":"traceutil/trace.go:171","msg":"trace[175450038] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"264.76222ms","start":"2026-02-20T10:05:42.628084Z","end":"2026-02-20T10:05:42.892846Z","steps":["trace[175450038] 'process raft request' (duration: 264.712907ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:42.893044Z","caller":"traceutil/trace.go:171","msg":"trace[799611326] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"665.620434ms","start":"2026-02-20T10:05:42.227391Z","end":"2026-02-20T10:05:42.893011Z","steps":["trace[799611326] 'process raft request' (duration: 665.188125ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:42.893163Z","caller":"traceutil/trace.go:171","msg":"trace[1046863520] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"665.689156ms","start":"2026-02-20T10:05:42.227424Z","end":"2026-02-20T10:05:42.893113Z","steps":["trace[1046863520] 'process raft request' (duration: 665.282919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:42.893255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:42.227361Z","time spent":"665.744599ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:05:42.89323Z","caller":"traceutil/trace.go:171","msg":"trace[827915378] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"665.047909ms","start":"2026-02-20T10:05:42.228137Z","end":"2026-02-20T10:05:42.893185Z","steps":["trace[827915378] 'process raft request' (duration: 664.623821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:42.893425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:42.227411Z","time spent":"665.870564ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:05:42.893582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:42.22813Z","time spent":"665.270228ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:05:49.005591Z","caller":"traceutil/trace.go:171","msg":"trace[926220806] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"155.137183ms","start":"2026-02-20T10:05:48.850429Z","end":"2026-02-20T10:05:49.005566Z","steps":["trace[926220806] 'process raft request' (duration: 154.933053ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:49.415613Z","caller":"traceutil/trace.go:171","msg":"trace[1930322787] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"404.73573ms","start":"2026-02-20T10:05:49.010841Z","end":"2026-02-20T10:05:49.415577Z","steps":["trace[1930322787] 'process raft request' (duration: 329.07326ms)","trace[1930322787] 'compare' (duration: 75.516644ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:05:49.415784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:49.010816Z","time spent":"404.870546ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:05:49.427284Z","caller":"traceutil/trace.go:171","msg":"trace[679007618] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"416.285006ms","start":"2026-02-20T10:05:49.010988Z","end":"2026-02-20T10:05:49.427273Z","steps":["trace[679007618] 'process raft request' (duration: 416.246064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:49.427347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:49.010956Z","time spent":"416.36098ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:05:49.427347Z","caller":"traceutil/trace.go:171","msg":"trace[1416187958] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"416.419743ms","start":"2026-02-20T10:05:49.01087Z","end":"2026-02-20T10:05:49.42729Z","steps":["trace[1416187958] 'process raft request' (duration: 416.303877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:49.427583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:49.010844Z","time spent":"416.603411ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:05:50.547667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289644967292094,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:05:51.048313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289644967292094,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:05:51.09684Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.033226261s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T10:05:51.09737Z","caller":"traceutil/trace.go:171","msg":"trace[1776563827] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"1.050045678s","start":"2026-02-20T10:05:50.047292Z","end":"2026-02-20T10:05:51.097338Z","steps":["trace[1776563827] 'read index received' (duration: 1.049754914s)","trace[1776563827] 'applied index is now lower than readState.Index' (duration: 289.724µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:05:51.097478Z","caller":"traceutil/trace.go:171","msg":"trace[967104611] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.144021457s","start":"2026-02-20T10:05:49.953445Z","end":"2026-02-20T10:05:51.097466Z","steps":["trace[967104611] 'process raft request' (duration: 1.143686092s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:51.097623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:49.953405Z","time spent":"1.144108811s","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:05:51.097873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027423491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zbjlf\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-20T10:05:51.097932Z","caller":"traceutil/trace.go:171","msg":"trace[680834551] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zbjlf; range_end:; response_count:1; response_revision:566; }","duration":"1.027536257s","start":"2026-02-20T10:05:50.07038Z","end":"2026-02-20T10:05:51.097916Z","steps":["trace[680834551] 'agreement among raft nodes before linearized reading' (duration: 1.027345608s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:51.097975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:50.07035Z","time spent":"1.02761472s","remote":"127.0.0.1:34848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-zbjlf\" "} {"level":"warn","ts":"2026-02-20T10:05:51.09807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017928635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:05:51.098102Z","caller":"traceutil/trace.go:171","msg":"trace[2015320876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"1.017980598s","start":"2026-02-20T10:05:50.080112Z","end":"2026-02-20T10:05:51.098092Z","steps":["trace[2015320876] 'agreement among raft nodes before linearized reading' (duration: 1.017924755s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:51.098072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050755449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-20T10:05:51.098124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:50.080101Z","time spent":"1.018017239s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T10:05:51.098198Z","caller":"traceutil/trace.go:171","msg":"trace[794583488] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:566; }","duration":"1.050948178s","start":"2026-02-20T10:05:50.047229Z","end":"2026-02-20T10:05:51.098177Z","steps":["trace[794583488] 'agreement among raft nodes before linearized reading' (duration: 1.050709808s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:51.098248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:50.047203Z","time spent":"1.051033922s","remote":"127.0.0.1:34710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.157\" "} {"level":"warn","ts":"2026-02-20T10:05:52.191933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.025548ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-02-20T10:05:52.192026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:51.09967Z","time spent":"1.092351369s","remote":"127.0.0.1:34710","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-20T10:05:52.484313Z","caller":"traceutil/trace.go:171","msg":"trace[1988001264] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.379478023s","start":"2026-02-20T10:05:51.104772Z","end":"2026-02-20T10:05:52.48425Z","steps":["trace[1988001264] 'process raft request' (duration: 1.326410224s)","trace[1988001264] 'compare' (duration: 51.433215ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:05:52.484581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:51.10474Z","time spent":"1.379685202s","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:05:52.484667Z","caller":"traceutil/trace.go:171","msg":"trace[1348945782] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:585; }","duration":"1.376637604s","start":"2026-02-20T10:05:51.107935Z","end":"2026-02-20T10:05:52.484573Z","steps":["trace[1348945782] 'read index received' (duration: 448.945142ms)","trace[1348945782] 'applied index is now lower than readState.Index' (duration: 927.688422ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:05:52.48501Z","caller":"traceutil/trace.go:171","msg":"trace[2069233759] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.37233466s","start":"2026-02-20T10:05:51.112645Z","end":"2026-02-20T10:05:52.48498Z","steps":["trace[2069233759] 'process raft request' (duration: 1.370610652s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:52.485175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:51.112634Z","time spent":"1.372408933s","remote":"127.0.0.1:34848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:05:52.485798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377851589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:05:52.485881Z","caller":"traceutil/trace.go:171","msg":"trace[356322808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"1.377950734s","start":"2026-02-20T10:05:51.107894Z","end":"2026-02-20T10:05:52.485844Z","steps":["trace[356322808] 'agreement among raft nodes before linearized reading' (duration: 1.377315995s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:52.48593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:51.107881Z","time spent":"1.378034777s","remote":"127.0.0.1:34668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T10:05:54.489429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999953359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-20T10:05:54.489597Z","caller":"traceutil/trace.go:171","msg":"trace[335091692] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00018454s","start":"2026-02-20T10:05:52.489382Z","end":"2026-02-20T10:05:54.489566Z","steps":["trace[335091692] 'agreement among raft nodes before linearized reading' (duration: 1.99996684s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:54.489993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:52.489363Z","time spent":"2.00061207s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 10:05:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T10:05:54.494791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.847819296s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:05:54.494898Z","caller":"traceutil/trace.go:171","msg":"trace[741689986] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"2.010130664s","start":"2026-02-20T10:05:52.484742Z","end":"2026-02-20T10:05:54.494873Z","steps":["trace[741689986] 'read index received' (duration: 162.107188ms)","trace[741689986] 'applied index is now lower than readState.Index' (duration: 1.848022116s)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:05:54.494973Z","caller":"traceutil/trace.go:171","msg":"trace[1173136609] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"2.301476668s","start":"2026-02-20T10:05:52.193484Z","end":"2026-02-20T10:05:54.49496Z","steps":["trace[1173136609] 'process raft request' (duration: 453.361488ms)","trace[1173136609] 'compare' (duration: 1.846735527s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:05:54.495039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:52.193452Z","time spent":"2.301554401s","remote":"127.0.0.1:34710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:05:54.49543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.06368793s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T10:05:54.495474Z","caller":"traceutil/trace.go:171","msg":"trace[26103055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"3.063758804s","start":"2026-02-20T10:05:51.431704Z","end":"2026-02-20T10:05:54.495463Z","steps":["trace[26103055] 'agreement among raft nodes before linearized reading' (duration: 3.063559055s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:54.495507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:51.431692Z","time spent":"3.063807706s","remote":"127.0.0.1:34928","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-20T10:05:54.495708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.064766669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T10:05:54.495736Z","caller":"traceutil/trace.go:171","msg":"trace[1389398959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"3.064831202s","start":"2026-02-20T10:05:51.430896Z","end":"2026-02-20T10:05:54.495727Z","steps":["trace[1389398959] 'agreement among raft nodes before linearized reading' (duration: 3.064744108s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:54.495763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:51.430874Z","time spent":"3.064883694s","remote":"127.0.0.1:34928","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-20T10:05:54.495937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.076067699s","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-20T10:05:54.495976Z","caller":"traceutil/trace.go:171","msg":"trace[1314010019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"3.076140163s","start":"2026-02-20T10:05:51.419827Z","end":"2026-02-20T10:05:54.495967Z","steps":["trace[1314010019] 'agreement among raft nodes before linearized reading' (duration: 3.076046849s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:54.496004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:51.419799Z","time spent":"3.076198575s","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T10:05:54.811749Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.659600216s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T10:05:56.504517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000297496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T10:05:56.504607Z","caller":"traceutil/trace.go:171","msg":"trace[1928808484] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000419432s","start":"2026-02-20T10:05:54.504166Z","end":"2026-02-20T10:05:56.504585Z","steps":["trace[1928808484] 'agreement among raft nodes before linearized reading' (duration: 2.000304736s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:56.504659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:54.504159Z","time spent":"2.000487695s","remote":"127.0.0.1:34668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T10:05:58.359212Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.547126829s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T10:05:58.359948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.797658ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:05:58.360037Z","caller":"traceutil/trace.go:171","msg":"trace[1308049208] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:569; }","duration":"210.876102ms","start":"2026-02-20T10:05:58.149128Z","end":"2026-02-20T10:05:58.360004Z","steps":["trace[1308049208] 'range keys from in-memory index tree' (duration: 210.784608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:58.360669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.548364677s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:05:58.361198Z","caller":"traceutil/trace.go:171","msg":"trace[608084092] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"3.866917303s","start":"2026-02-20T10:05:54.49423Z","end":"2026-02-20T10:05:58.361147Z","steps":["trace[608084092] 'process raft request' (duration: 3.866745295s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:58.361413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:54.494209Z","time spent":"3.867098621s","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T10:05:58.361434Z","caller":"traceutil/trace.go:171","msg":"trace[337172167] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"3.85529765s","start":"2026-02-20T10:05:54.506106Z","end":"2026-02-20T10:05:58.361403Z","steps":["trace[337172167] 'process raft request' (duration: 3.854979835s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:05:58.361456Z","caller":"traceutil/trace.go:171","msg":"trace[1492506352] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"3.855365853s","start":"2026-02-20T10:05:54.506052Z","end":"2026-02-20T10:05:58.361418Z","steps":["trace[1492506352] 'process raft request' (duration: 3.855012927s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:58.361507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:54.506076Z","time spent":"3.855397145s","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:05:58.361614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:54.506042Z","time spent":"3.855488989s","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:05:58.36185Z","caller":"traceutil/trace.go:171","msg":"trace[1283753627] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"3.8613757s","start":"2026-02-20T10:05:54.500462Z","end":"2026-02-20T10:05:58.361838Z","steps":["trace[1283753627] 'process raft request' (duration: 3.860571572s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:58.361908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:54.500451Z","time spent":"3.861424281s","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:05:58.362152Z","caller":"traceutil/trace.go:171","msg":"trace[64900774] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:589; }","duration":"3.867197706s","start":"2026-02-20T10:05:54.494931Z","end":"2026-02-20T10:05:58.362129Z","steps":["trace[64900774] 'read index received' (duration: 317.222714ms)","trace[64900774] 'applied index is now lower than readState.Index' (duration: 3.549972782s)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:05:58.362225Z","caller":"traceutil/trace.go:171","msg":"trace[364961936] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"5.210178666s","start":"2026-02-20T10:05:53.152005Z","end":"2026-02-20T10:05:58.362184Z","steps":["trace[364961936] 'process raft request' (duration: 1.660217524s)","trace[364961936] 'compare' (duration: 3.547980979s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:05:58.362439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:53.151986Z","time spent":"5.210329633s","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:05:58.364858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.865926361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-20T10:05:58.364919Z","caller":"traceutil/trace.go:171","msg":"trace[425716419] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:574; }","duration":"3.865990594s","start":"2026-02-20T10:05:54.498913Z","end":"2026-02-20T10:05:58.364903Z","steps":["trace[425716419] 'agreement among raft nodes before linearized reading' (duration: 3.865881589s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:58.364967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:54.498891Z","time spent":"3.866064937s","remote":"127.0.0.1:34828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-20T10:05:58.364961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.871858322s","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-20T10:05:58.365032Z","caller":"traceutil/trace.go:171","msg":"trace[1460720060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"3.871988489s","start":"2026-02-20T10:05:54.493028Z","end":"2026-02-20T10:05:58.365017Z","steps":["trace[1460720060] 'agreement among raft nodes before linearized reading' (duration: 3.869105334s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:58.365038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.871402726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zbjlf\" ","response":"range_response_count:1 size:16818"} {"level":"warn","ts":"2026-02-20T10:05:58.365077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:54.493006Z","time spent":"3.872062593s","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T10:05:58.365085Z","caller":"traceutil/trace.go:171","msg":"trace[925349636] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zbjlf; range_end:; response_count:1; response_revision:574; }","duration":"5.871516041s","start":"2026-02-20T10:05:52.493557Z","end":"2026-02-20T10:05:58.365073Z","steps":["trace[925349636] 'agreement among raft nodes before linearized reading' (duration: 5.868685959s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:58.365113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:52.493523Z","time spent":"5.871585833s","remote":"127.0.0.1:34848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16840,"request content":"key:\"/registry/pods/kube-system/cilium-zbjlf\" "} {"level":"warn","ts":"2026-02-20T10:05:58.365211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.85985646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:05:58.365268Z","caller":"traceutil/trace.go:171","msg":"trace[1361520873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"1.859910412s","start":"2026-02-20T10:05:56.505343Z","end":"2026-02-20T10:05:58.365253Z","steps":["trace[1361520873] 'agreement among raft nodes before linearized reading' (duration: 1.859830538s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:58.365314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:56.505297Z","time spent":"1.860006927s","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T10:05:59.435933Z","caller":"traceutil/trace.go:171","msg":"trace[1669093641] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"328.11558ms","start":"2026-02-20T10:05:59.10778Z","end":"2026-02-20T10:05:59.435895Z","steps":["trace[1669093641] 'process raft request' (duration: 327.90369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:05:59.436818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:05:59.10775Z","time spent":"328.283607ms","remote":"127.0.0.1:34848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16901,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:06:01.606608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079206698s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:06:01.606931Z","caller":"traceutil/trace.go:171","msg":"trace[1325389031] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:609; }","duration":"502.480031ms","start":"2026-02-20T10:06:01.104434Z","end":"2026-02-20T10:06:01.606914Z","steps":["trace[1325389031] 'read index received' (duration: 39.572µs)","trace[1325389031] 'applied index is now lower than readState.Index' (duration: 502.439549ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:06:01.607029Z","caller":"traceutil/trace.go:171","msg":"trace[682224338] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.216263009s","start":"2026-02-20T10:06:00.390738Z","end":"2026-02-20T10:06:01.607001Z","steps":["trace[682224338] 'process raft request' (duration: 136.561138ms)","trace[682224338] 'compare' (duration: 1.078717964s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:06:01.607172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.735722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zbjlf\" ","response":"range_response_count:1 size:17171"} {"level":"info","ts":"2026-02-20T10:06:01.607229Z","caller":"traceutil/trace.go:171","msg":"trace[1777155140] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zbjlf; range_end:; response_count:1; response_revision:589; }","duration":"502.832087ms","start":"2026-02-20T10:06:01.10439Z","end":"2026-02-20T10:06:01.607222Z","steps":["trace[1777155140] 'agreement among raft nodes before linearized reading' (duration: 502.587036ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:01.607167Z","caller":"traceutil/trace.go:171","msg":"trace[1406250567] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.209947561s","start":"2026-02-20T10:06:00.397139Z","end":"2026-02-20T10:06:01.607086Z","steps":["trace[1406250567] 'process raft request' (duration: 1.20972016s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:01.60718Z","caller":"traceutil/trace.go:171","msg":"trace[723936509] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"330.671086ms","start":"2026-02-20T10:06:01.27649Z","end":"2026-02-20T10:06:01.607161Z","steps":["trace[723936509] 'process raft request' (duration: 330.557671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:01.607357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:06:01.104369Z","time spent":"502.89852ms","remote":"127.0.0.1:34848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17193,"request content":"key:\"/registry/pods/kube-system/cilium-zbjlf\" "} {"level":"warn","ts":"2026-02-20T10:06:01.607408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:06:00.39713Z","time spent":"1.210169752s","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T10:06:01.607177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:06:00.390719Z","time spent":"1.216371994s","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:06:01.607361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:06:01.276463Z","time spent":"330.850995ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:06:03.924803Z","caller":"traceutil/trace.go:171","msg":"trace[782770583] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"312.145972ms","start":"2026-02-20T10:06:03.612632Z","end":"2026-02-20T10:06:03.924778Z","steps":["trace[782770583] 'read index received' (duration: 254.195836ms)","trace[782770583] 'applied index is now lower than readState.Index' (duration: 57.948896ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:06:03.925022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.379064ms","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-20T10:06:03.925055Z","caller":"traceutil/trace.go:171","msg":"trace[1357359208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"312.448437ms","start":"2026-02-20T10:06:03.612596Z","end":"2026-02-20T10:06:03.925045Z","steps":["trace[1357359208] 'agreement among raft nodes before linearized reading' (duration: 312.281349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:03.925089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:06:03.612585Z","time spent":"312.49694ms","remote":"127.0.0.1:34928","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":"info","ts":"2026-02-20T10:06:03.925274Z","caller":"traceutil/trace.go:171","msg":"trace[2080863105] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"357.521749ms","start":"2026-02-20T10:06:03.567712Z","end":"2026-02-20T10:06:03.925234Z","steps":["trace[2080863105] 'process raft request' (duration: 299.117041ms)","trace[2080863105] 'compare' (duration: 57.627801ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:06:03.925471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:06:03.567702Z","time spent":"357.677836ms","remote":"127.0.0.1:34722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T10:06:04.062271Z","caller":"traceutil/trace.go:171","msg":"trace[1440686730] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"281.038989ms","start":"2026-02-20T10:06:03.781209Z","end":"2026-02-20T10:06:04.062248Z","steps":["trace[1440686730] 'process raft request' (duration: 280.984976ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:04.062356Z","caller":"traceutil/trace.go:171","msg":"trace[1237022640] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"300.169503ms","start":"2026-02-20T10:06:03.762136Z","end":"2026-02-20T10:06:04.062305Z","steps":["trace[1237022640] 'process raft request' (duration: 299.939362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:04.062531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:06:03.762104Z","time spent":"300.349891ms","remote":"127.0.0.1:34722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T10:06:04.209261Z","caller":"traceutil/trace.go:171","msg":"trace[1821258971] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:652; }","duration":"247.232464ms","start":"2026-02-20T10:06:03.961995Z","end":"2026-02-20T10:06:04.209227Z","steps":["trace[1821258971] 'read index received' (duration: 100.100131ms)","trace[1821258971] 'applied index is now lower than readState.Index' (duration: 147.131383ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:06:04.209574Z","caller":"traceutil/trace.go:171","msg":"trace[1520576677] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"267.812386ms","start":"2026-02-20T10:06:03.94173Z","end":"2026-02-20T10:06:04.209543Z","steps":["trace[1520576677] 'process raft request' (duration: 204.470629ms)","trace[1520576677] 'compare' (duration: 62.749098ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:06:04.209634Z","caller":"traceutil/trace.go:171","msg":"trace[946341448] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"245.88717ms","start":"2026-02-20T10:06:03.963717Z","end":"2026-02-20T10:06:04.209604Z","steps":["trace[946341448] 'process raft request' (duration: 245.434388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:04.209741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.723217ms","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-20T10:06:04.209871Z","caller":"traceutil/trace.go:171","msg":"trace[1098663490] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:634; }","duration":"247.894976ms","start":"2026-02-20T10:06:03.961956Z","end":"2026-02-20T10:06:04.20985Z","steps":["trace[1098663490] 'agreement among raft nodes before linearized reading' (duration: 247.57193ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:04.241537Z","caller":"traceutil/trace.go:171","msg":"trace[1985296197] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"112.504013ms","start":"2026-02-20T10:06:04.129003Z","end":"2026-02-20T10:06:04.241507Z","steps":["trace[1985296197] 'process raft request' (duration: 112.284192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:04.241587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.947695ms","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-20T10:06:04.241667Z","caller":"traceutil/trace.go:171","msg":"trace[40054013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:635; }","duration":"224.072302ms","start":"2026-02-20T10:06:04.01757Z","end":"2026-02-20T10:06:04.241643Z","steps":["trace[40054013] 'agreement among raft nodes before linearized reading' (duration: 223.850901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:04.241902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.740545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:06:04.241967Z","caller":"traceutil/trace.go:171","msg":"trace[1554271411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"160.83617ms","start":"2026-02-20T10:06:04.081112Z","end":"2026-02-20T10:06:04.241948Z","steps":["trace[1554271411] 'agreement among raft nodes before linearized reading' (duration: 160.727495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:04.242505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.582903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zbjlf\" ","response":"range_response_count:1 size:17670"} {"level":"info","ts":"2026-02-20T10:06:04.242568Z","caller":"traceutil/trace.go:171","msg":"trace[1441318638] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zbjlf; range_end:; response_count:1; response_revision:635; }","duration":"114.686338ms","start":"2026-02-20T10:06:04.127866Z","end":"2026-02-20T10:06:04.242552Z","steps":["trace[1441318638] 'agreement among raft nodes before linearized reading' (duration: 114.493919ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:04.382205Z","caller":"traceutil/trace.go:171","msg":"trace[1416078599] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"139.112439ms","start":"2026-02-20T10:06:04.243074Z","end":"2026-02-20T10:06:04.382187Z","steps":["trace[1416078599] 'read index received' (duration: 96.360599ms)","trace[1416078599] 'applied index is now lower than readState.Index' (duration: 42.75112ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:06:04.382249Z","caller":"traceutil/trace.go:171","msg":"trace[2025635213] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"164.771879ms","start":"2026-02-20T10:06:04.21744Z","end":"2026-02-20T10:06:04.382211Z","steps":["trace[2025635213] 'process raft request' (duration: 121.980517ms)","trace[2025635213] 'compare' (duration: 42.616213ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:06:04.382322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.230084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:06:04.382298Z","caller":"traceutil/trace.go:171","msg":"trace[1864422457] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"161.124763ms","start":"2026-02-20T10:06:04.221143Z","end":"2026-02-20T10:06:04.382268Z","steps":["trace[1864422457] 'process raft request' (duration: 161.009228ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:04.382367Z","caller":"traceutil/trace.go:171","msg":"trace[1538933425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"139.300248ms","start":"2026-02-20T10:06:04.243038Z","end":"2026-02-20T10:06:04.382338Z","steps":["trace[1538933425] 'agreement among raft nodes before linearized reading' (duration: 139.238785ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:04.400731Z","caller":"traceutil/trace.go:171","msg":"trace[2081763631] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"145.002311ms","start":"2026-02-20T10:06:04.255713Z","end":"2026-02-20T10:06:04.400715Z","steps":["trace[2081763631] 'process raft request' (duration: 144.940948ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:04.401028Z","caller":"traceutil/trace.go:171","msg":"trace[1380099108] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"148.563831ms","start":"2026-02-20T10:06:04.252456Z","end":"2026-02-20T10:06:04.40102Z","steps":["trace[1380099108] 'process raft request' (duration: 148.092359ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:04.561734Z","caller":"traceutil/trace.go:171","msg":"trace[631692504] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"102.051562ms","start":"2026-02-20T10:06:04.459637Z","end":"2026-02-20T10:06:04.561688Z","steps":["trace[631692504] 'process raft request' (duration: 101.647823ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:04.561782Z","caller":"traceutil/trace.go:171","msg":"trace[150199015] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"100.114029ms","start":"2026-02-20T10:06:04.46165Z","end":"2026-02-20T10:06:04.561764Z","steps":["trace[150199015] 'process raft request' (duration: 99.676168ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:04.562138Z","caller":"traceutil/trace.go:171","msg":"trace[428829297] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"103.496641ms","start":"2026-02-20T10:06:04.458625Z","end":"2026-02-20T10:06:04.562121Z","steps":["trace[428829297] 'process raft request' (duration: 50.985064ms)","trace[428829297] 'compare' (duration: 51.51959ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:06:05.377395Z","caller":"traceutil/trace.go:171","msg":"trace[1663015004] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"416.38278ms","start":"2026-02-20T10:06:04.960974Z","end":"2026-02-20T10:06:05.377356Z","steps":["trace[1663015004] 'read index received' (duration: 416.118877ms)","trace[1663015004] 'applied index is now lower than readState.Index' (duration: 262.103µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:06:05.377413Z","caller":"traceutil/trace.go:171","msg":"trace[1725459168] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"611.075912ms","start":"2026-02-20T10:06:04.766299Z","end":"2026-02-20T10:06:05.377375Z","steps":["trace[1725459168] 'process raft request' (duration: 610.815579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:05.377606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:06:04.766266Z","time spent":"611.261171ms","remote":"127.0.0.1:34722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T10:06:05.377793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.779589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-20T10:06:05.37785Z","caller":"traceutil/trace.go:171","msg":"trace[915905443] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:646; }","duration":"416.903625ms","start":"2026-02-20T10:06:04.960926Z","end":"2026-02-20T10:06:05.37783Z","steps":["trace[915905443] 'agreement among raft nodes before linearized reading' (duration: 416.617181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:05.377894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:06:04.960903Z","time spent":"416.981929ms","remote":"127.0.0.1:34722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-02-20T10:06:05.503391Z","caller":"traceutil/trace.go:171","msg":"trace[539963427] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"125.853837ms","start":"2026-02-20T10:06:05.377497Z","end":"2026-02-20T10:06:05.503351Z","steps":["trace[539963427] 'read index received' (duration: 97.841719ms)","trace[539963427] 'applied index is now lower than readState.Index' (duration: 28.011258ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:06:05.503484Z","caller":"traceutil/trace.go:171","msg":"trace[1849340189] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"314.080345ms","start":"2026-02-20T10:06:05.189361Z","end":"2026-02-20T10:06:05.503442Z","steps":["trace[1849340189] 'process raft request' (duration: 285.970633ms)","trace[1849340189] 'compare' (duration: 27.798927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:06:05.503741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:06:05.189333Z","time spent":"314.284196ms","remote":"127.0.0.1:34722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T10:06:05.503981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.596573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-20T10:06:05.50403Z","caller":"traceutil/trace.go:171","msg":"trace[1656099226] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:647; }","duration":"142.691117ms","start":"2026-02-20T10:06:05.361324Z","end":"2026-02-20T10:06:05.504015Z","steps":["trace[1656099226] 'agreement among raft nodes before linearized reading' (duration: 142.502248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:05.504158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.338686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:06:05.504323Z","caller":"traceutil/trace.go:171","msg":"trace[1590668059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"422.64684ms","start":"2026-02-20T10:06:05.081666Z","end":"2026-02-20T10:06:05.504313Z","steps":["trace[1590668059] 'agreement among raft nodes before linearized reading' (duration: 421.903345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:05.504426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:06:05.08162Z","time spent":"422.797547ms","remote":"127.0.0.1:34668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T10:06:05.5043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.292209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zbjlf\" ","response":"range_response_count:1 size:17918"} {"level":"info","ts":"2026-02-20T10:06:05.50458Z","caller":"traceutil/trace.go:171","msg":"trace[1268801274] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zbjlf; range_end:; response_count:1; response_revision:647; }","duration":"355.552422ms","start":"2026-02-20T10:06:05.148998Z","end":"2026-02-20T10:06:05.50455Z","steps":["trace[1268801274] 'agreement among raft nodes before linearized reading' (duration: 355.185194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:05.504657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:06:05.148956Z","time spent":"355.674328ms","remote":"127.0.0.1:34848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17940,"request content":"key:\"/registry/pods/kube-system/cilium-zbjlf\" "} {"level":"info","ts":"2026-02-20T10:06:05.538336Z","caller":"traceutil/trace.go:171","msg":"trace[1601365975] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"146.656718ms","start":"2026-02-20T10:06:05.391643Z","end":"2026-02-20T10:06:05.538299Z","steps":["trace[1601365975] 'process raft request' (duration: 146.407846ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:13.473465Z","caller":"traceutil/trace.go:171","msg":"trace[354221162] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"187.928173ms","start":"2026-02-20T10:06:13.285515Z","end":"2026-02-20T10:06:13.473443Z","steps":["trace[354221162] 'process raft request' (duration: 187.806927ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:13.476339Z","caller":"traceutil/trace.go:171","msg":"trace[1650096083] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"189.427767ms","start":"2026-02-20T10:06:13.28688Z","end":"2026-02-20T10:06:13.476308Z","steps":["trace[1650096083] 'process raft request' (duration: 189.162494ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:13.476588Z","caller":"traceutil/trace.go:171","msg":"trace[2093480280] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"188.134343ms","start":"2026-02-20T10:06:13.288442Z","end":"2026-02-20T10:06:13.476577Z","steps":["trace[2093480280] 'process raft request' (duration: 187.793546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:13.476952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.742382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-dp4mb\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-02-20T10:06:13.477017Z","caller":"traceutil/trace.go:171","msg":"trace[380076848] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-dp4mb; range_end:; response_count:1; response_revision:738; }","duration":"189.832266ms","start":"2026-02-20T10:06:13.287156Z","end":"2026-02-20T10:06:13.476988Z","steps":["trace[380076848] 'agreement among raft nodes before linearized reading' (duration: 189.583074ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:13.476323Z","caller":"traceutil/trace.go:171","msg":"trace[490700363] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:761; }","duration":"189.108441ms","start":"2026-02-20T10:06:13.287182Z","end":"2026-02-20T10:06:13.476291Z","steps":["trace[490700363] 'read index received' (duration: 186.233128ms)","trace[490700363] 'applied index is now lower than readState.Index' (duration: 2.873673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:06:13.477237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.90505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:06:13.477288Z","caller":"traceutil/trace.go:171","msg":"trace[122972028] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:738; }","duration":"145.008186ms","start":"2026-02-20T10:06:13.332264Z","end":"2026-02-20T10:06:13.477273Z","steps":["trace[122972028] 'agreement among raft nodes before linearized reading' (duration: 144.908461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:20.82113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.403555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T10:06:20.821226Z","caller":"traceutil/trace.go:171","msg":"trace[611318125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:779; }","duration":"111.564862ms","start":"2026-02-20T10:06:20.709642Z","end":"2026-02-20T10:06:20.821207Z","steps":["trace[611318125] 'range keys from in-memory index tree' (duration: 111.210355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:20.82125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.407603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-20T10:06:20.821298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.558411ms","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-20T10:06:20.821371Z","caller":"traceutil/trace.go:171","msg":"trace[1247459611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:779; }","duration":"109.593473ms","start":"2026-02-20T10:06:20.711757Z","end":"2026-02-20T10:06:20.821351Z","steps":["trace[1247459611] 'range keys from in-memory index tree' (duration: 109.197832ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:06:20.821404Z","caller":"traceutil/trace.go:171","msg":"trace[1154117521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"109.704838ms","start":"2026-02-20T10:06:20.711675Z","end":"2026-02-20T10:06:20.82138Z","steps":["trace[1154117521] 'range keys from in-memory index tree' (duration: 109.418104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:20.821827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.626314ms","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-20T10:06:20.821873Z","caller":"traceutil/trace.go:171","msg":"trace[2044743627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:779; }","duration":"336.674656ms","start":"2026-02-20T10:06:20.485187Z","end":"2026-02-20T10:06:20.821862Z","steps":["trace[2044743627] 'range keys from in-memory index tree' (duration: 336.494297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:06:20.821906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:06:20.485115Z","time spent":"336.781782ms","remote":"127.0.0.1:34928","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-20T10:07:25.438576Z","caller":"traceutil/trace.go:171","msg":"trace[1842080772] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"206.919385ms","start":"2026-02-20T10:07:25.23162Z","end":"2026-02-20T10:07:25.43854Z","steps":["trace[1842080772] 'process raft request' (duration: 206.740245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:33.984934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289644967293222,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T10:07:34.364654Z","caller":"traceutil/trace.go:171","msg":"trace[1338094392] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"880.536951ms","start":"2026-02-20T10:07:33.484082Z","end":"2026-02-20T10:07:34.364619Z","steps":["trace[1338094392] 'read index received' (duration: 880.389503ms)","trace[1338094392] 'applied index is now lower than readState.Index' (duration: 145.798µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:07:34.364781Z","caller":"traceutil/trace.go:171","msg":"trace[1641148578] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"881.74371ms","start":"2026-02-20T10:07:33.482994Z","end":"2026-02-20T10:07:34.364738Z","steps":["trace[1641148578] 'process raft request' (duration: 881.433032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:34.365016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.899043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T10:07:34.365063Z","caller":"traceutil/trace.go:171","msg":"trace[2117685026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"881.049731ms","start":"2026-02-20T10:07:33.484002Z","end":"2026-02-20T10:07:34.365051Z","steps":["trace[2117685026] 'agreement among raft nodes before linearized reading' (duration: 880.800967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:34.365007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.994813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T10:07:34.365086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:07:33.482972Z","time spent":"881.911841ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:07:34.365108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:07:33.483986Z","time spent":"881.113665ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-20T10:07:34.365146Z","caller":"traceutil/trace.go:171","msg":"trace[458777760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"282.189894ms","start":"2026-02-20T10:07:34.08293Z","end":"2026-02-20T10:07:34.36512Z","steps":["trace[458777760] 'agreement among raft nodes before linearized reading' (duration: 281.94604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:34.365555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.338032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T10:07:34.365613Z","caller":"traceutil/trace.go:171","msg":"trace[2051208263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1027; }","duration":"876.450318ms","start":"2026-02-20T10:07:33.489146Z","end":"2026-02-20T10:07:34.365597Z","steps":["trace[2051208263] 'agreement among raft nodes before linearized reading' (duration: 875.694185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:34.365661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:07:33.489117Z","time spent":"876.535783ms","remote":"127.0.0.1:34928","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-20T10:07:34.36569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.477387ms","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-20T10:07:34.366904Z","caller":"traceutil/trace.go:171","msg":"trace[1511101100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1027; }","duration":"861.054968ms","start":"2026-02-20T10:07:33.50578Z","end":"2026-02-20T10:07:34.366835Z","steps":["trace[1511101100] 'agreement among raft nodes before linearized reading' (duration: 859.186471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:34.367031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:07:33.505743Z","time spent":"861.245829ms","remote":"127.0.0.1:34928","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-20T10:07:34.488204Z","caller":"traceutil/trace.go:171","msg":"trace[333788951] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"117.777168ms","start":"2026-02-20T10:07:34.37039Z","end":"2026-02-20T10:07:34.488167Z","steps":["trace[333788951] 'process raft request' (duration: 21.370392ms)","trace[333788951] 'compare' (duration: 96.059596ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:07:34.489564Z","caller":"traceutil/trace.go:171","msg":"trace[1359252845] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"118.452897ms","start":"2026-02-20T10:07:34.371079Z","end":"2026-02-20T10:07:34.489532Z","steps":["trace[1359252845] 'process raft request' (duration: 118.239135ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:07:34.489685Z","caller":"traceutil/trace.go:171","msg":"trace[62208132] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"117.426219ms","start":"2026-02-20T10:07:34.372237Z","end":"2026-02-20T10:07:34.489664Z","steps":["trace[62208132] 'process raft request' (duration: 117.250069ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:07:38.558746Z","caller":"traceutil/trace.go:171","msg":"trace[2012141426] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"121.710005ms","start":"2026-02-20T10:07:38.437008Z","end":"2026-02-20T10:07:38.558718Z","steps":["trace[2012141426] 'read index received' (duration: 121.523174ms)","trace[2012141426] 'applied index is now lower than readState.Index' (duration: 185.581µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:07:38.558843Z","caller":"traceutil/trace.go:171","msg":"trace[1842929363] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"141.356583ms","start":"2026-02-20T10:07:38.417438Z","end":"2026-02-20T10:07:38.558794Z","steps":["trace[1842929363] 'process raft request' (duration: 141.11983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:38.559039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.007712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-20T10:07:38.559257Z","caller":"traceutil/trace.go:171","msg":"trace[341852029] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1041; }","duration":"122.292249ms","start":"2026-02-20T10:07:38.436948Z","end":"2026-02-20T10:07:38.55924Z","steps":["trace[341852029] 'agreement among raft nodes before linearized reading' (duration: 121.930528ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:07:44.758978Z","caller":"traceutil/trace.go:171","msg":"trace[116087682] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"154.694587ms","start":"2026-02-20T10:07:44.604249Z","end":"2026-02-20T10:07:44.758944Z","steps":["trace[116087682] 'read index received' (duration: 154.39494ms)","trace[116087682] 'applied index is now lower than readState.Index' (duration: 298.217µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:07:44.759046Z","caller":"traceutil/trace.go:171","msg":"trace[474827306] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"167.816393ms","start":"2026-02-20T10:07:44.591177Z","end":"2026-02-20T10:07:44.758993Z","steps":["trace[474827306] 'process raft request' (duration: 167.566349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:44.759207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.942562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-02-20T10:07:44.759314Z","caller":"traceutil/trace.go:171","msg":"trace[2137581199] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:1059; }","duration":"155.112141ms","start":"2026-02-20T10:07:44.604179Z","end":"2026-02-20T10:07:44.759291Z","steps":["trace[2137581199] 'agreement among raft nodes before linearized reading' (duration: 154.91302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:44.759487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.246853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:node-controller\" ","response":"range_response_count:1 size:835"} {"level":"info","ts":"2026-02-20T10:07:44.75954Z","caller":"traceutil/trace.go:171","msg":"trace[773167971] range","detail":"{range_begin:/registry/clusterroles/system:controller:node-controller; range_end:; response_count:1; response_revision:1059; }","duration":"132.36601ms","start":"2026-02-20T10:07:44.627159Z","end":"2026-02-20T10:07:44.759525Z","steps":["trace[773167971] 'agreement among raft nodes before linearized reading' (duration: 131.998269ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:07:45.027171Z","caller":"traceutil/trace.go:171","msg":"trace[89498629] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"130.170505ms","start":"2026-02-20T10:07:44.896966Z","end":"2026-02-20T10:07:45.027136Z","steps":["trace[89498629] 'read index received' (duration: 129.91598ms)","trace[89498629] 'applied index is now lower than readState.Index' (duration: 253.154µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:07:45.027338Z","caller":"traceutil/trace.go:171","msg":"trace[735146929] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"147.488272ms","start":"2026-02-20T10:07:44.879796Z","end":"2026-02-20T10:07:45.027284Z","steps":["trace[735146929] 'process raft request' (duration: 147.154313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:45.027414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.417378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-02-20T10:07:45.027472Z","caller":"traceutil/trace.go:171","msg":"trace[1069304986] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1063; }","duration":"130.523264ms","start":"2026-02-20T10:07:44.89692Z","end":"2026-02-20T10:07:45.027444Z","steps":["trace[1069304986] 'agreement among raft nodes before linearized reading' (duration: 130.386816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:45.027646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.227082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:1076"} {"level":"info","ts":"2026-02-20T10:07:45.027735Z","caller":"traceutil/trace.go:171","msg":"trace[1353332922] range","detail":"{range_begin:/registry/clusterroles/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1063; }","duration":"128.36051ms","start":"2026-02-20T10:07:44.899349Z","end":"2026-02-20T10:07:45.02771Z","steps":["trace[1353332922] 'agreement among raft nodes before linearized reading' (duration: 128.106225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:45.583539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.589984ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-20T10:07:45.583635Z","caller":"traceutil/trace.go:171","msg":"trace[427181677] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"215.773287ms","start":"2026-02-20T10:07:45.367841Z","end":"2026-02-20T10:07:45.583615Z","steps":["trace[427181677] 'read index received' (duration: 77.038295ms)","trace[427181677] 'applied index is now lower than readState.Index' (duration: 138.733872ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:07:45.58376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.779511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-02-20T10:07:45.583782Z","caller":"traceutil/trace.go:171","msg":"trace[1389407097] range","detail":"{range_begin:/registry/clusterroles/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:1063; }","duration":"213.866207ms","start":"2026-02-20T10:07:45.369906Z","end":"2026-02-20T10:07:45.583772Z","steps":["trace[1389407097] 'agreement among raft nodes before linearized reading' (duration: 213.791763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:45.583806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.937836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:disruption-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-02-20T10:07:45.584008Z","caller":"traceutil/trace.go:171","msg":"trace[1731790485] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1063; }","duration":"216.162319ms","start":"2026-02-20T10:07:45.367789Z","end":"2026-02-20T10:07:45.583951Z","steps":["trace[1731790485] 'agreement among raft nodes before linearized reading' (duration: 215.899194ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:07:45.962587Z","caller":"traceutil/trace.go:171","msg":"trace[1049168499] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"293.565864ms","start":"2026-02-20T10:07:45.668993Z","end":"2026-02-20T10:07:45.962559Z","steps":["trace[1049168499] 'read index received' (duration: 293.348951ms)","trace[1049168499] 'applied index is now lower than readState.Index' (duration: 215.602µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:07:45.962696Z","caller":"traceutil/trace.go:171","msg":"trace[1262425356] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"308.101212ms","start":"2026-02-20T10:07:45.654553Z","end":"2026-02-20T10:07:45.962655Z","steps":["trace[1262425356] 'process raft request' (duration: 307.814976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:45.962911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.163728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:disruption-controller\" ","response":"range_response_count:1 size:759"} {"level":"warn","ts":"2026-02-20T10:07:45.963005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:07:45.654531Z","time spent":"308.261131ms","remote":"127.0.0.1:34836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5220,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:07:45.962986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.899453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-02-20T10:07:45.96304Z","caller":"traceutil/trace.go:171","msg":"trace[925434534] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1064; }","duration":"290.337188ms","start":"2026-02-20T10:07:45.672672Z","end":"2026-02-20T10:07:45.963009Z","steps":["trace[925434534] 'agreement among raft nodes before linearized reading' (duration: 290.085603ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:07:45.963104Z","caller":"traceutil/trace.go:171","msg":"trace[869354308] range","detail":"{range_begin:/registry/clusterroles/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:1064; }","duration":"294.146988ms","start":"2026-02-20T10:07:45.668932Z","end":"2026-02-20T10:07:45.963079Z","steps":["trace[869354308] 'agreement among raft nodes before linearized reading' (duration: 293.768196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:07:45.962917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.633293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:07:45.963291Z","caller":"traceutil/trace.go:171","msg":"trace[737722932] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1064; }","duration":"266.054406ms","start":"2026-02-20T10:07:45.697173Z","end":"2026-02-20T10:07:45.963228Z","steps":["trace[737722932] 'agreement among raft nodes before linearized reading' (duration: 265.60818ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:07:50.69721Z","caller":"traceutil/trace.go:171","msg":"trace[1831200348] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"101.050948ms","start":"2026-02-20T10:07:50.596104Z","end":"2026-02-20T10:07:50.697155Z","steps":["trace[1831200348] 'process raft request' (duration: 100.807924ms)"],"step_count":1}