{"level":"warn","ts":"2026-02-18T05:13:53.508531Z","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-18T05:13:53.50912Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-18T05:13:53.509765Z","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-18T05:13:53.510261Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-02-18T05:13:53.510326Z","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-18T05:13:53.513027Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-02-18T05:13:53.516049Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-18T05:13:53.524709Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.801746ms"} {"level":"info","ts":"2026-02-18T05:13:53.546802Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-02-18T05:13:53.546962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T05:13:53.547052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-02-18T05:13:53.547128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T05:13:53.547181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-02-18T05:13:53.547313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-02-18T05:13:53.564791Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T05:13:53.572566Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T05:13:53.591943Z","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-18T05:13:53.604924Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T05:13:53.607425Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T05:13:53.608274Z","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-18T05:13:53.612677Z","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-18T05:13:53.61282Z","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-18T05:13:53.640357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-02-18T05:13:53.640455Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-02-18T05:13:53.640739Z","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-18T05:13:53.64091Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T05:13:53.640938Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T05:13:53.641008Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-02-18T05:13:53.641017Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-02-18T05:13:54.048935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T05:13:54.048992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T05:13:54.049053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-02-18T05:13:54.049066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-02-18T05:13:54.049076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-02-18T05:13:54.049118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-02-18T05:13:54.049126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-02-18T05:13:54.050613Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:13:54.05718Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T05:13:54.057288Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:13:54.057616Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:13:54.058573Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T05:13:54.058589Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T05:13:54.063107Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T05:13:54.10326Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-02-18T05:13:54.103513Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:13:54.103662Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:13:54.103679Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:13:56.678132Z","caller":"traceutil/trace.go:171","msg":"trace[216889359] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"105.651543ms","start":"2026-02-18T05:13:56.572456Z","end":"2026-02-18T05:13:56.678107Z","steps":["trace[216889359] 'process raft request' (duration: 87.469873ms)","trace[216889359] 'compare' (duration: 18.062189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:13:56.919121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.637106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-18T05:13:56.919421Z","caller":"traceutil/trace.go:171","msg":"trace[1353683734] transaction","detail":"{read_only:false; response_revision:106; number_of_response:1; }","duration":"174.571754ms","start":"2026-02-18T05:13:56.744825Z","end":"2026-02-18T05:13:56.919397Z","steps":["trace[1353683734] 'process raft request' (duration: 56.099282ms)","trace[1353683734] 'compare' (duration: 117.413524ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:57.054132Z","caller":"traceutil/trace.go:171","msg":"trace[594634172] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"130.09942ms","start":"2026-02-18T05:13:56.924012Z","end":"2026-02-18T05:13:57.054111Z","steps":["trace[594634172] 'process raft request' (duration: 129.943329ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:57.054273Z","caller":"traceutil/trace.go:171","msg":"trace[418103927] linearizableReadLoop","detail":"{readStateIndex:112; appliedIndex:112; }","duration":"127.237248ms","start":"2026-02-18T05:13:56.927022Z","end":"2026-02-18T05:13:57.054259Z","steps":["trace[418103927] 'read index received' (duration: 127.230758ms)","trace[418103927] 'applied index is now lower than readState.Index' (duration: 5.53µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:13:57.054414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.369279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T05:13:57.054891Z","caller":"traceutil/trace.go:171","msg":"trace[460859433] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:107; }","duration":"127.873553ms","start":"2026-02-18T05:13:56.927Z","end":"2026-02-18T05:13:57.054874Z","steps":["trace[460859433] 'agreement among raft nodes before linearized reading' (duration: 127.305439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:57.064903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.567808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T05:13:57.064976Z","caller":"traceutil/trace.go:171","msg":"trace[884505984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:108; }","duration":"124.661199ms","start":"2026-02-18T05:13:56.940307Z","end":"2026-02-18T05:13:57.064968Z","steps":["trace[884505984] 'agreement among raft nodes before linearized reading' (duration: 124.259275ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:57.065056Z","caller":"traceutil/trace.go:171","msg":"trace[1444659655] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"137.702468ms","start":"2026-02-18T05:13:56.927346Z","end":"2026-02-18T05:13:57.065049Z","steps":["trace[1444659655] 'process raft request' (duration: 137.135614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:57.588287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.09101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-18T05:13:57.588439Z","caller":"traceutil/trace.go:171","msg":"trace[1441995877] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"476.719713ms","start":"2026-02-18T05:13:57.111659Z","end":"2026-02-18T05:13:57.588379Z","steps":["trace[1441995877] 'read index received' (duration: 236.380441ms)","trace[1441995877] 'applied index is now lower than readState.Index' (duration: 240.336512ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:57.588632Z","caller":"traceutil/trace.go:171","msg":"trace[1072868912] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"496.947727ms","start":"2026-02-18T05:13:57.091621Z","end":"2026-02-18T05:13:57.588568Z","steps":["trace[1072868912] 'process raft request' (duration: 256.510395ms)","trace[1072868912] 'compare' (duration: 239.954049ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:13:57.589022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.323657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T05:13:57.589211Z","caller":"traceutil/trace.go:171","msg":"trace[1341737473] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:112; }","duration":"477.436078ms","start":"2026-02-18T05:13:57.111623Z","end":"2026-02-18T05:13:57.589059Z","steps":["trace[1341737473] 'agreement among raft nodes before linearized reading' (duration: 477.031505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:57.589324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:57.111606Z","time spent":"477.64713ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-02-18T05:13:57.589187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:57.091609Z","time spent":"497.27335ms","remote":"127.0.0.1:42070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:13:57.904142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.807173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-18T05:13:57.90435Z","caller":"traceutil/trace.go:171","msg":"trace[1566658160] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"263.915062ms","start":"2026-02-18T05:13:57.640376Z","end":"2026-02-18T05:13:57.904291Z","steps":["trace[1566658160] 'process raft request' (duration: 55.229573ms)","trace[1566658160] 'compare' (duration: 207.625621ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:57.953162Z","caller":"traceutil/trace.go:171","msg":"trace[2123414506] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"300.875595ms","start":"2026-02-18T05:13:57.652262Z","end":"2026-02-18T05:13:57.953137Z","steps":["trace[2123414506] 'process raft request' (duration: 300.731394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:57.953462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:57.652253Z","time spent":"301.130707ms","remote":"127.0.0.1:42070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:13:58.307253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.719696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-18T05:13:58.307792Z","caller":"traceutil/trace.go:171","msg":"trace[156416650] linearizableReadLoop","detail":"{readStateIndex:126; appliedIndex:124; }","duration":"166.764092ms","start":"2026-02-18T05:13:58.141Z","end":"2026-02-18T05:13:58.307764Z","steps":["trace[156416650] 'read index received' (duration: 4.815827ms)","trace[156416650] 'applied index is now lower than readState.Index' (duration: 161.946514ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:13:58.308161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.136734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T05:13:58.308303Z","caller":"traceutil/trace.go:171","msg":"trace[650964449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:121; }","duration":"167.268235ms","start":"2026-02-18T05:13:58.140995Z","end":"2026-02-18T05:13:58.308263Z","steps":["trace[650964449] 'agreement among raft nodes before linearized reading' (duration: 167.048484ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:58.307661Z","caller":"traceutil/trace.go:171","msg":"trace[994213268] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"249.362712ms","start":"2026-02-18T05:13:58.058271Z","end":"2026-02-18T05:13:58.307634Z","steps":["trace[994213268] 'process raft request' (duration: 126.201452ms)","trace[994213268] 'compare' (duration: 122.619495ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:58.308785Z","caller":"traceutil/trace.go:171","msg":"trace[1145961862] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"249.970477ms","start":"2026-02-18T05:13:58.058773Z","end":"2026-02-18T05:13:58.308743Z","steps":["trace[1145961862] 'process raft request' (duration: 248.568056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:58.772321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.71661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-18T05:13:58.772484Z","caller":"traceutil/trace.go:171","msg":"trace[709085516] transaction","detail":"{read_only:false; response_revision:125; number_of_response:1; }","duration":"291.709695ms","start":"2026-02-18T05:13:58.480756Z","end":"2026-02-18T05:13:58.772465Z","steps":["trace[709085516] 'process raft request' (duration: 73.789483ms)","trace[709085516] 'compare' (duration: 217.483829ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:59.347844Z","caller":"traceutil/trace.go:171","msg":"trace[233995936] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"191.994377ms","start":"2026-02-18T05:13:59.155822Z","end":"2026-02-18T05:13:59.347816Z","steps":["trace[233995936] 'process raft request' (duration: 118.944403ms)","trace[233995936] 'compare' (duration: 72.916043ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:59.552197Z","caller":"traceutil/trace.go:171","msg":"trace[970479853] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"135.78205ms","start":"2026-02-18T05:13:59.416391Z","end":"2026-02-18T05:13:59.552173Z","steps":["trace[970479853] 'process raft request' (duration: 86.97378ms)","trace[970479853] 'compare' (duration: 48.612389ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:04.32929Z","caller":"traceutil/trace.go:171","msg":"trace[2040577835] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:336; }","duration":"127.400007ms","start":"2026-02-18T05:14:04.201877Z","end":"2026-02-18T05:14:04.329277Z","steps":["trace[2040577835] 'read index received' (duration: 34.629127ms)","trace[2040577835] 'applied index is now lower than readState.Index' (duration: 92.7703ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:04.32945Z","caller":"traceutil/trace.go:171","msg":"trace[1917055886] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"155.155864ms","start":"2026-02-18T05:14:04.174287Z","end":"2026-02-18T05:14:04.329443Z","steps":["trace[1917055886] 'process raft request' (duration: 62.228533ms)","trace[1917055886] 'compare' (duration: 92.691019ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:04.329557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.67684ms","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-18T05:14:04.329573Z","caller":"traceutil/trace.go:171","msg":"trace[864306500] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:329; }","duration":"127.72841ms","start":"2026-02-18T05:14:04.201839Z","end":"2026-02-18T05:14:04.329568Z","steps":["trace[864306500] 'agreement among raft nodes before linearized reading' (duration: 127.69003ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:05.735176Z","caller":"traceutil/trace.go:171","msg":"trace[256863895] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"118.816448ms","start":"2026-02-18T05:14:05.616335Z","end":"2026-02-18T05:14:05.735151Z","steps":["trace[256863895] 'process raft request' (duration: 87.122862ms)","trace[256863895] 'compare' (duration: 28.497656ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:06.824226Z","caller":"traceutil/trace.go:171","msg":"trace[1499006342] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"165.891994ms","start":"2026-02-18T05:14:06.65831Z","end":"2026-02-18T05:14:06.824202Z","steps":["trace[1499006342] 'process raft request' (duration: 159.185433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:06.825524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.5016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-18T05:14:06.825563Z","caller":"traceutil/trace.go:171","msg":"trace[914080657] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:364; }","duration":"120.562602ms","start":"2026-02-18T05:14:06.704991Z","end":"2026-02-18T05:14:06.825554Z","steps":["trace[914080657] 'agreement among raft nodes before linearized reading' (duration: 120.430527ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:06.825393Z","caller":"traceutil/trace.go:171","msg":"trace[1953597527] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"118.997369ms","start":"2026-02-18T05:14:06.704999Z","end":"2026-02-18T05:14:06.823996Z","steps":["trace[1953597527] 'read index received' (duration: 112.412223ms)","trace[1953597527] 'applied index is now lower than readState.Index' (duration: 6.584226ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:06.826704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.39395ms","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":"info","ts":"2026-02-18T05:14:06.826764Z","caller":"traceutil/trace.go:171","msg":"trace[1183771897] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:364; }","duration":"106.510315ms","start":"2026-02-18T05:14:06.720246Z","end":"2026-02-18T05:14:06.826756Z","steps":["trace[1183771897] 'agreement among raft nodes before linearized reading' (duration: 106.3826ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:10.510263Z","caller":"traceutil/trace.go:171","msg":"trace[1585987674] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"120.52324ms","start":"2026-02-18T05:14:10.389714Z","end":"2026-02-18T05:14:10.510237Z","steps":["trace[1585987674] 'process raft request' (duration: 120.134655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:10.722788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.260695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:10.722879Z","caller":"traceutil/trace.go:171","msg":"trace[1671429131] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"208.505513ms","start":"2026-02-18T05:14:10.514361Z","end":"2026-02-18T05:14:10.722866Z","steps":["trace[1671429131] 'process raft request' (duration: 77.931396ms)","trace[1671429131] 'compare' (duration: 129.928223ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:11.875879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.981129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:11.87616Z","caller":"traceutil/trace.go:171","msg":"trace[131613244] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"195.505865ms","start":"2026-02-18T05:14:11.680619Z","end":"2026-02-18T05:14:11.876125Z","steps":["trace[131613244] 'process raft request' (duration: 77.025907ms)","trace[131613244] 'compare' (duration: 117.884996ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:12.701569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408501543177894,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:14:14.201182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999953605s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T05:14:14.201332Z","caller":"traceutil/trace.go:171","msg":"trace[2004325517] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000123221s","start":"2026-02-18T05:14:12.201187Z","end":"2026-02-18T05:14:14.20131Z","steps":["trace[2004325517] 'agreement among raft nodes before linearized reading' (duration: 1.999946614s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:14.201383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:12.201134Z","time spent":"2.000238275s","remote":"127.0.0.1:42016","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-18T05:14:14.254607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.229234374s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:14.254808Z","caller":"traceutil/trace.go:171","msg":"trace[505100059] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:433; }","duration":"2.053599482s","start":"2026-02-18T05:14:12.201192Z","end":"2026-02-18T05:14:14.254791Z","steps":["trace[505100059] 'read index received' (duration: 853.116902ms)","trace[505100059] 'applied index is now lower than readState.Index' (duration: 1.20048164s)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:14.255018Z","caller":"traceutil/trace.go:171","msg":"trace[1850844176] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"2.323535921s","start":"2026-02-18T05:14:11.93145Z","end":"2026-02-18T05:14:14.254986Z","steps":["trace[1850844176] 'process raft request' (duration: 2.323273731s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:14.255275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:11.931444Z","time spent":"2.323752339s","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:14.255488Z","caller":"traceutil/trace.go:171","msg":"trace[1182857326] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"2.324257837s","start":"2026-02-18T05:14:11.931219Z","end":"2026-02-18T05:14:14.255476Z","steps":["trace[1182857326] 'process raft request' (duration: 94.061098ms)","trace[1182857326] 'compare' (duration: 2.229120309s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:14.25554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:11.931206Z","time spent":"2.324308759s","remote":"127.0.0.1:42176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4048,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:14:14.255111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740858861s","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-18T05:14:14.255734Z","caller":"traceutil/trace.go:171","msg":"trace[1090467226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:425; }","duration":"1.741507955s","start":"2026-02-18T05:14:12.514216Z","end":"2026-02-18T05:14:14.255724Z","steps":["trace[1090467226] 'agreement among raft nodes before linearized reading' (duration: 1.740862501s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:14.255762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:12.514198Z","time spent":"1.741557217s","remote":"127.0.0.1:42256","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-18T05:14:14.255321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.945205ms","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-18T05:14:14.255837Z","caller":"traceutil/trace.go:171","msg":"trace[1781290923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:425; }","duration":"355.481605ms","start":"2026-02-18T05:14:13.900349Z","end":"2026-02-18T05:14:14.255831Z","steps":["trace[1781290923] 'agreement among raft nodes before linearized reading' (duration: 354.957126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:14.255855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:13.900336Z","time spent":"355.514566ms","remote":"127.0.0.1:42256","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-18T05:14:14.255344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526673616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T05:14:14.255939Z","caller":"traceutil/trace.go:171","msg":"trace[226462804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:425; }","duration":"1.527301899s","start":"2026-02-18T05:14:12.728631Z","end":"2026-02-18T05:14:14.255933Z","steps":["trace[226462804] 'agreement among raft nodes before linearized reading' (duration: 1.526699797s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:14.255957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:12.728611Z","time spent":"1.52734181s","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:14:14.255292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.101728ms","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-18T05:14:14.256865Z","caller":"traceutil/trace.go:171","msg":"trace[405734171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:425; }","duration":"346.695247ms","start":"2026-02-18T05:14:13.910159Z","end":"2026-02-18T05:14:14.256854Z","steps":["trace[405734171] 'agreement among raft nodes before linearized reading' (duration: 345.111098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:14.256895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:13.910142Z","time spent":"346.744139ms","remote":"127.0.0.1:42256","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-18T05:14:15.093182Z","caller":"traceutil/trace.go:171","msg":"trace[1282573202] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"816.43647ms","start":"2026-02-18T05:14:14.276697Z","end":"2026-02-18T05:14:15.093134Z","steps":["trace[1282573202] 'process raft request' (duration: 816.254974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:15.093335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:14.276689Z","time spent":"816.592076ms","remote":"127.0.0.1:42070","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-18T05:14:15.131401Z","caller":"traceutil/trace.go:171","msg":"trace[1748271743] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:435; }","duration":"245.899269ms","start":"2026-02-18T05:14:14.885488Z","end":"2026-02-18T05:14:15.131387Z","steps":["trace[1748271743] 'read index received' (duration: 207.49311ms)","trace[1748271743] 'applied index is now lower than readState.Index' (duration: 38.405409ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:15.131513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.016173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-02-18T05:14:15.13155Z","caller":"traceutil/trace.go:171","msg":"trace[550722663] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:430; }","duration":"246.084196ms","start":"2026-02-18T05:14:14.885456Z","end":"2026-02-18T05:14:15.13154Z","steps":["trace[550722663] 'agreement among raft nodes before linearized reading' (duration: 245.991002ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:15.131886Z","caller":"traceutil/trace.go:171","msg":"trace[2113561310] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"849.189431ms","start":"2026-02-18T05:14:14.282685Z","end":"2026-02-18T05:14:15.131875Z","steps":["trace[2113561310] 'process raft request' (duration: 848.506266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:15.131962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:14.282675Z","time spent":"849.245864ms","remote":"127.0.0.1:42256","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-18T05:14:15.132751Z","caller":"traceutil/trace.go:171","msg":"trace[1376400023] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"850.007021ms","start":"2026-02-18T05:14:14.28273Z","end":"2026-02-18T05:14:15.132737Z","steps":["trace[1376400023] 'process raft request' (duration: 848.533708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:15.132831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:14.282726Z","time spent":"850.067453ms","remote":"127.0.0.1:42256","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-18T05:14:15.133124Z","caller":"traceutil/trace.go:171","msg":"trace[425725154] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"829.796796ms","start":"2026-02-18T05:14:14.303315Z","end":"2026-02-18T05:14:15.133111Z","steps":["trace[425725154] 'process raft request' (duration: 828.003671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:15.133323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:14.303297Z","time spent":"829.865089ms","remote":"127.0.0.1:42256","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-18T05:14:15.133667Z","caller":"traceutil/trace.go:171","msg":"trace[1437425195] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"829.463244ms","start":"2026-02-18T05:14:14.304191Z","end":"2026-02-18T05:14:15.133655Z","steps":["trace[1437425195] 'process raft request' (duration: 827.15861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:15.133738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:14.304185Z","time spent":"829.520806ms","remote":"127.0.0.1:42256","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-18T05:14:16.678628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.291115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:16.678799Z","caller":"traceutil/trace.go:171","msg":"trace[780943284] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:458; }","duration":"323.860429ms","start":"2026-02-18T05:14:16.354925Z","end":"2026-02-18T05:14:16.678785Z","steps":["trace[780943284] 'read index received' (duration: 158.433309ms)","trace[780943284] 'applied index is now lower than readState.Index' (duration: 165.42413ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:16.678895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.769415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3833"} {"level":"info","ts":"2026-02-18T05:14:16.678911Z","caller":"traceutil/trace.go:171","msg":"trace[560779318] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:450; }","duration":"381.801857ms","start":"2026-02-18T05:14:16.297104Z","end":"2026-02-18T05:14:16.678906Z","steps":["trace[560779318] 'agreement among raft nodes before linearized reading' (duration: 381.717064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:16.678933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:16.297099Z","time spent":"381.829508ms","remote":"127.0.0.1:42444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3855,"request content":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" "} {"level":"info","ts":"2026-02-18T05:14:16.679064Z","caller":"traceutil/trace.go:171","msg":"trace[459779028] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"384.578437ms","start":"2026-02-18T05:14:16.294477Z","end":"2026-02-18T05:14:16.679056Z","steps":["trace[459779028] 'process raft request' (duration: 218.803394ms)","trace[459779028] 'compare' (duration: 165.168991ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:16.679096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:16.294472Z","time spent":"384.607228ms","remote":"127.0.0.1:42070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:14:16.679181Z","caller":"traceutil/trace.go:171","msg":"trace[495292617] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"384.637188ms","start":"2026-02-18T05:14:16.294536Z","end":"2026-02-18T05:14:16.679173Z","steps":["trace[495292617] 'process raft request' (duration: 384.163131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:16.679207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:16.294532Z","time spent":"384.66206ms","remote":"127.0.0.1:42070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:14:16.679354Z","caller":"traceutil/trace.go:171","msg":"trace[2059605381] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"381.555368ms","start":"2026-02-18T05:14:16.29777Z","end":"2026-02-18T05:14:16.679325Z","steps":["trace[2059605381] 'process raft request' (duration: 380.966987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:16.679393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:16.297763Z","time spent":"381.607059ms","remote":"127.0.0.1:42444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:16.683917Z","caller":"traceutil/trace.go:171","msg":"trace[172741167] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"317.060023ms","start":"2026-02-18T05:14:16.366832Z","end":"2026-02-18T05:14:16.683892Z","steps":["trace[172741167] 'process raft request' (duration: 316.924679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:16.688165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:16.366789Z","time spent":"321.237024ms","remote":"127.0.0.1:42420","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-18T05:14:19.306432Z","caller":"traceutil/trace.go:171","msg":"trace[2069671946] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"115.539356ms","start":"2026-02-18T05:14:19.190874Z","end":"2026-02-18T05:14:19.306413Z","steps":["trace[2069671946] 'process raft request' (duration: 115.433442ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:19.462707Z","caller":"traceutil/trace.go:171","msg":"trace[82034058] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"269.532262ms","start":"2026-02-18T05:14:19.193147Z","end":"2026-02-18T05:14:19.462679Z","steps":["trace[82034058] 'process raft request' (duration: 243.560067ms)","trace[82034058] 'compare' (duration: 25.614062ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:19.462935Z","caller":"traceutil/trace.go:171","msg":"trace[652882532] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"269.719548ms","start":"2026-02-18T05:14:19.193207Z","end":"2026-02-18T05:14:19.462927Z","steps":["trace[652882532] 'process raft request' (duration: 269.20287ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:19.463069Z","caller":"traceutil/trace.go:171","msg":"trace[12158555] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"269.824182ms","start":"2026-02-18T05:14:19.193236Z","end":"2026-02-18T05:14:19.46306Z","steps":["trace[12158555] 'process raft request' (duration: 269.335795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:27.59555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.248784ms","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-18T05:14:27.595634Z","caller":"traceutil/trace.go:171","msg":"trace[1399358260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"101.377058ms","start":"2026-02-18T05:14:27.494238Z","end":"2026-02-18T05:14:27.595615Z","steps":["trace[1399358260] 'range keys from in-memory index tree' (duration: 101.194663ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:27.749246Z","caller":"traceutil/trace.go:171","msg":"trace[1944141768] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"140.572381ms","start":"2026-02-18T05:14:27.608647Z","end":"2026-02-18T05:14:27.749219Z","steps":["trace[1944141768] 'process raft request' (duration: 140.471688ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:27.751154Z","caller":"traceutil/trace.go:171","msg":"trace[605201889] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"143.293387ms","start":"2026-02-18T05:14:27.607845Z","end":"2026-02-18T05:14:27.751139Z","steps":["trace[605201889] 'process raft request' (duration: 140.095995ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:27.751383Z","caller":"traceutil/trace.go:171","msg":"trace[118471910] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"143.132252ms","start":"2026-02-18T05:14:27.60824Z","end":"2026-02-18T05:14:27.751372Z","steps":["trace[118471910] 'process raft request' (duration: 140.831989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:30.80275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04536873s","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-18T05:14:30.802804Z","caller":"traceutil/trace.go:171","msg":"trace[478001752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"1.045459653s","start":"2026-02-18T05:14:29.757332Z","end":"2026-02-18T05:14:30.802792Z","steps":["trace[478001752] 'range keys from in-memory index tree' (duration: 1.045216845s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:30.802847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:29.757316Z","time spent":"1.045519095s","remote":"127.0.0.1:42256","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-18T05:14:30.802987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043390277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T05:14:30.803007Z","caller":"traceutil/trace.go:171","msg":"trace[1661568522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"1.043422179s","start":"2026-02-18T05:14:29.759579Z","end":"2026-02-18T05:14:30.803001Z","steps":["trace[1661568522] 'range keys from in-memory index tree' (duration: 1.043317216s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:30.803024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:29.759572Z","time spent":"1.0434479s","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:14:30.803292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.38347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T05:14:30.803311Z","caller":"traceutil/trace.go:171","msg":"trace[1032518173] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:526; }","duration":"760.436921ms","start":"2026-02-18T05:14:30.042868Z","end":"2026-02-18T05:14:30.803305Z","steps":["trace[1032518173] 'range keys from in-memory index tree' (duration: 760.280748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:30.803327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:30.04285Z","time spent":"760.473363ms","remote":"127.0.0.1:42030","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.216\" "} {"level":"warn","ts":"2026-02-18T05:14:30.803749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.429401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:30.803769Z","caller":"traceutil/trace.go:171","msg":"trace[1957310665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"601.476913ms","start":"2026-02-18T05:14:30.202286Z","end":"2026-02-18T05:14:30.803763Z","steps":["trace[1957310665] 'range keys from in-memory index tree' (duration: 601.37262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:30.803787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:30.20225Z","time spent":"601.533314ms","remote":"127.0.0.1:42016","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-18T05:14:33.558968Z","caller":"traceutil/trace.go:171","msg":"trace[1452052708] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"137.191145ms","start":"2026-02-18T05:14:33.421753Z","end":"2026-02-18T05:14:33.558944Z","steps":["trace[1452052708] 'process raft request' (duration: 137.063341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:38.189592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408501543178178,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:14:38.690737Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408501543178178,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:14:38.789146Z","caller":"traceutil/trace.go:171","msg":"trace[1000229010] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"1.099902252s","start":"2026-02-18T05:14:37.689226Z","end":"2026-02-18T05:14:38.789128Z","steps":["trace[1000229010] 'read index received' (duration: 1.099749038s)","trace[1000229010] 'applied index is now lower than readState.Index' (duration: 152.484µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:38.789227Z","caller":"traceutil/trace.go:171","msg":"trace[798552270] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.216237302s","start":"2026-02-18T05:14:37.572984Z","end":"2026-02-18T05:14:38.789221Z","steps":["trace[798552270] 'process raft request' (duration: 1.216040406s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:38.789309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:37.57297Z","time spent":"1.216276634s","remote":"127.0.0.1:42256","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-18T05:14:38.789465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100237532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kmlkh\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-18T05:14:38.789486Z","caller":"traceutil/trace.go:171","msg":"trace[649068458] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kmlkh; range_end:; response_count:1; response_revision:569; }","duration":"1.100280573s","start":"2026-02-18T05:14:37.689199Z","end":"2026-02-18T05:14:38.789479Z","steps":["trace[649068458] 'agreement among raft nodes before linearized reading' (duration: 1.100192881s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:38.789505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:37.689185Z","time spent":"1.100316374s","remote":"127.0.0.1:42178","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-kmlkh\" "} {"level":"warn","ts":"2026-02-18T05:14:38.78966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067539431s","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-18T05:14:38.789711Z","caller":"traceutil/trace.go:171","msg":"trace[1081118055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"1.067622263s","start":"2026-02-18T05:14:37.722076Z","end":"2026-02-18T05:14:38.789699Z","steps":["trace[1081118055] 'agreement among raft nodes before linearized reading' (duration: 1.067467339s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:38.789739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:37.722066Z","time spent":"1.067667744s","remote":"127.0.0.1:42256","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-18T05:14:38.789787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.159653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:38.789814Z","caller":"traceutil/trace.go:171","msg":"trace[2096056094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"589.205465ms","start":"2026-02-18T05:14:38.2006Z","end":"2026-02-18T05:14:38.789805Z","steps":["trace[2096056094] 'agreement among raft nodes before linearized reading' (duration: 589.169593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:38.789831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:38.200587Z","time spent":"589.241135ms","remote":"127.0.0.1:42016","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-18T05:14:39.391775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408501543178182,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:14:39.584526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.842857ms","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-18T05:14:39.584612Z","caller":"traceutil/trace.go:171","msg":"trace[400549574] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:569; }","duration":"195.94855ms","start":"2026-02-18T05:14:39.388651Z","end":"2026-02-18T05:14:39.5846Z","steps":["trace[400549574] 'range keys from in-memory index tree' (duration: 195.810726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:39.584805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.571835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:39.584844Z","caller":"traceutil/trace.go:171","msg":"trace[345521078] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"787.240315ms","start":"2026-02-18T05:14:38.797593Z","end":"2026-02-18T05:14:39.584833Z","steps":["trace[345521078] 'process raft request' (duration: 622.599978ms)","trace[345521078] 'compare' (duration: 164.097321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:39.584877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:38.797577Z","time spent":"787.283586ms","remote":"127.0.0.1:42256","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-18T05:14:40.40098Z","caller":"traceutil/trace.go:171","msg":"trace[1632881504] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"1.5101907s","start":"2026-02-18T05:14:38.890739Z","end":"2026-02-18T05:14:40.40093Z","steps":["trace[1632881504] 'read index received' (duration: 529.460133ms)","trace[1632881504] 'applied index is now lower than readState.Index' (duration: 980.729887ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:40.4013Z","caller":"traceutil/trace.go:171","msg":"trace[540847113] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.601004609s","start":"2026-02-18T05:14:38.800286Z","end":"2026-02-18T05:14:40.401291Z","steps":["trace[540847113] 'process raft request' (duration: 1.600502645s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:40.401377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:38.800267Z","time spent":"1.6010544s","remote":"127.0.0.1:42178","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-18T05:14:40.401554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.510819268s","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-18T05:14:40.401577Z","caller":"traceutil/trace.go:171","msg":"trace[2118283574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"1.510864658s","start":"2026-02-18T05:14:38.890704Z","end":"2026-02-18T05:14:40.401569Z","steps":["trace[2118283574] 'agreement among raft nodes before linearized reading' (duration: 1.510783406s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:40.401599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:38.890672Z","time spent":"1.51092118s","remote":"127.0.0.1:42256","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-18T05:14:40.401726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.507114092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T05:14:40.401745Z","caller":"traceutil/trace.go:171","msg":"trace[209534616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"1.507146572s","start":"2026-02-18T05:14:38.894592Z","end":"2026-02-18T05:14:40.401738Z","steps":["trace[209534616] 'agreement among raft nodes before linearized reading' (duration: 1.507095611s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:40.401762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:38.894584Z","time spent":"1.507173344s","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:14:40.401984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.190448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:40.402055Z","caller":"traceutil/trace.go:171","msg":"trace[839374431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"203.297481ms","start":"2026-02-18T05:14:40.198748Z","end":"2026-02-18T05:14:40.402045Z","steps":["trace[839374431] 'agreement among raft nodes before linearized reading' (duration: 203.217139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:40.402207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.862457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T05:14:40.402226Z","caller":"traceutil/trace.go:171","msg":"trace[723895160] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:571; }","duration":"358.907469ms","start":"2026-02-18T05:14:40.043313Z","end":"2026-02-18T05:14:40.40222Z","steps":["trace[723895160] 'agreement among raft nodes before linearized reading' (duration: 358.845857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:40.402244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:40.043289Z","time spent":"358.950199ms","remote":"127.0.0.1:42030","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.216\" "} {"level":"warn","ts":"2026-02-18T05:14:41.296872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408501543178189,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:14:41.798005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408501543178189,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:14:41.916608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.44304609s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:41.916762Z","caller":"traceutil/trace.go:171","msg":"trace[190184970] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.505234928s","start":"2026-02-18T05:14:40.411511Z","end":"2026-02-18T05:14:41.916746Z","steps":["trace[190184970] 'process raft request' (duration: 1.505180256s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:41.916821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:40.411506Z","time spent":"1.505286009s","remote":"127.0.0.1:42256","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-18T05:14:41.917118Z","caller":"traceutil/trace.go:171","msg":"trace[1587136114] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.505945838s","start":"2026-02-18T05:14:40.411158Z","end":"2026-02-18T05:14:41.917104Z","steps":["trace[1587136114] 'process raft request' (duration: 62.327962ms)","trace[1587136114] 'compare' (duration: 1.442172166s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:41.917176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:40.41115Z","time spent":"1.505996359s","remote":"127.0.0.1:42256","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-18T05:14:42.298731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408501543178189,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:14:42.799505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408501543178189,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:14:43.259875Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.786441247s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:14:43.32133Z","caller":"traceutil/trace.go:171","msg":"trace[1105107982] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"2.525130315s","start":"2026-02-18T05:14:40.796182Z","end":"2026-02-18T05:14:43.321312Z","steps":["trace[1105107982] 'read index received' (duration: 2.463791431s)","trace[1105107982] 'applied index is now lower than readState.Index' (duration: 61.338044ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:43.32143Z","caller":"traceutil/trace.go:171","msg":"trace[2117286837] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"2.897354426s","start":"2026-02-18T05:14:40.424068Z","end":"2026-02-18T05:14:43.321422Z","steps":["trace[2117286837] 'process raft request' (duration: 2.835972781s)","trace[2117286837] 'compare' (duration: 60.708137ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:43.321499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:40.42406Z","time spent":"2.897389497s","remote":"127.0.0.1:42030","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-18T05:14:43.321714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.525530407s","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-18T05:14:43.321736Z","caller":"traceutil/trace.go:171","msg":"trace[133327390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"2.525585398s","start":"2026-02-18T05:14:40.796145Z","end":"2026-02-18T05:14:43.32173Z","steps":["trace[133327390] 'agreement among raft nodes before linearized reading' (duration: 2.525469614s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:43.321756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:40.796128Z","time spent":"2.525623649s","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T05:14:43.32189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.73181562s","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-18T05:14:43.321917Z","caller":"traceutil/trace.go:171","msg":"trace[1494496687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"1.731879091s","start":"2026-02-18T05:14:41.59003Z","end":"2026-02-18T05:14:43.321909Z","steps":["trace[1494496687] 'agreement among raft nodes before linearized reading' (duration: 1.73181029s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:43.321939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:41.590011Z","time spent":"1.731922293s","remote":"127.0.0.1:42256","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-18T05:14:43.322094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123675818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:43.322117Z","caller":"traceutil/trace.go:171","msg":"trace[882763972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"1.123726249s","start":"2026-02-18T05:14:42.198385Z","end":"2026-02-18T05:14:43.322111Z","steps":["trace[882763972] 'agreement among raft nodes before linearized reading' (duration: 1.123690768s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:43.322134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:42.19837Z","time spent":"1.12376054s","remote":"127.0.0.1:42016","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-18T05:14:44.343891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.682491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:44.34397Z","caller":"traceutil/trace.go:171","msg":"trace[378791729] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"359.928025ms","start":"2026-02-18T05:14:43.984024Z","end":"2026-02-18T05:14:44.343952Z","steps":["trace[378791729] 'process raft request' (duration: 248.108061ms)","trace[378791729] 'compare' (duration: 111.604118ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:44.344003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:43.984012Z","time spent":"359.977426ms","remote":"127.0.0.1:42256","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-18T05:14:46.356117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.500689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T05:14:46.356327Z","caller":"traceutil/trace.go:171","msg":"trace[1811854730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"370.772256ms","start":"2026-02-18T05:14:45.985542Z","end":"2026-02-18T05:14:46.356314Z","steps":["trace[1811854730] 'range keys from in-memory index tree' (duration: 370.351855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:46.356443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:45.985522Z","time spent":"370.90988ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T05:14:46.377238Z","caller":"traceutil/trace.go:171","msg":"trace[1704932658] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"314.066947ms","start":"2026-02-18T05:14:46.063098Z","end":"2026-02-18T05:14:46.377165Z","steps":["trace[1704932658] 'process raft request' (duration: 313.936774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:46.377747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:46.063074Z","time spent":"314.610751ms","remote":"127.0.0.1:42032","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-18T05:14:46.397852Z","caller":"traceutil/trace.go:171","msg":"trace[59143657] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"195.480772ms","start":"2026-02-18T05:14:46.202361Z","end":"2026-02-18T05:14:46.397841Z","steps":["trace[59143657] 'read index received' (duration: 175.266849ms)","trace[59143657] 'applied index is now lower than readState.Index' (duration: 20.213353ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:46.398002Z","caller":"traceutil/trace.go:171","msg":"trace[2057970156] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"136.865337ms","start":"2026-02-18T05:14:46.261131Z","end":"2026-02-18T05:14:46.397996Z","steps":["trace[2057970156] 'process raft request' (duration: 136.62567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:46.398193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.823051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:46.398211Z","caller":"traceutil/trace.go:171","msg":"trace[1205430618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"195.886452ms","start":"2026-02-18T05:14:46.202319Z","end":"2026-02-18T05:14:46.398206Z","steps":["trace[1205430618] 'agreement among raft nodes before linearized reading' (duration: 195.849231ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:46.853324Z","caller":"traceutil/trace.go:171","msg":"trace[83687798] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"192.74622ms","start":"2026-02-18T05:14:46.660558Z","end":"2026-02-18T05:14:46.853304Z","steps":["trace[83687798] 'process raft request' (duration: 192.596596ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:46.858343Z","caller":"traceutil/trace.go:171","msg":"trace[1636276678] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:642; }","duration":"134.097874ms","start":"2026-02-18T05:14:46.724228Z","end":"2026-02-18T05:14:46.858326Z","steps":["trace[1636276678] 'read index received' (duration: 134.089744ms)","trace[1636276678] 'applied index is now lower than readState.Index' (duration: 7.12µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:46.858999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.752931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kmlkh\" ","response":"range_response_count:1 size:17415"} {"level":"info","ts":"2026-02-18T05:14:46.860019Z","caller":"traceutil/trace.go:171","msg":"trace[1709428454] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kmlkh; range_end:; response_count:1; response_revision:625; }","duration":"135.809039ms","start":"2026-02-18T05:14:46.724195Z","end":"2026-02-18T05:14:46.860004Z","steps":["trace[1709428454] 'agreement among raft nodes before linearized reading' (duration: 134.665409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:47.157195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.490854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:47.157369Z","caller":"traceutil/trace.go:171","msg":"trace[739584150] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"294.231455ms","start":"2026-02-18T05:14:46.863127Z","end":"2026-02-18T05:14:47.157358Z","steps":["trace[739584150] 'read index received' (duration: 183.545516ms)","trace[739584150] 'applied index is now lower than readState.Index' (duration: 110.685409ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:47.157418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.284096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:47.157436Z","caller":"traceutil/trace.go:171","msg":"trace[1657450199] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:628; }","duration":"294.313218ms","start":"2026-02-18T05:14:46.863116Z","end":"2026-02-18T05:14:47.157429Z","steps":["trace[1657450199] 'agreement among raft nodes before linearized reading' (duration: 294.273157ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:47.157549Z","caller":"traceutil/trace.go:171","msg":"trace[1309125504] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"432.035907ms","start":"2026-02-18T05:14:46.725508Z","end":"2026-02-18T05:14:47.157544Z","steps":["trace[1309125504] 'process raft request' (duration: 321.153683ms)","trace[1309125504] 'compare' (duration: 110.392501ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:47.157687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:46.725497Z","time spent":"432.17574ms","remote":"127.0.0.1:42070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":749,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:14:47.157963Z","caller":"traceutil/trace.go:171","msg":"trace[1522063086] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"294.877753ms","start":"2026-02-18T05:14:46.863079Z","end":"2026-02-18T05:14:47.157957Z","steps":["trace[1522063086] 'process raft request' (duration: 294.192625ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:47.158212Z","caller":"traceutil/trace.go:171","msg":"trace[2056068795] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"286.177813ms","start":"2026-02-18T05:14:46.872017Z","end":"2026-02-18T05:14:47.158194Z","steps":["trace[2056068795] 'process raft request' (duration: 285.31565ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:47.205848Z","caller":"traceutil/trace.go:171","msg":"trace[770040714] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"147.073539ms","start":"2026-02-18T05:14:47.058758Z","end":"2026-02-18T05:14:47.205831Z","steps":["trace[770040714] 'process raft request' (duration: 146.982787ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:47.318531Z","caller":"traceutil/trace.go:171","msg":"trace[511031893] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"156.561737ms","start":"2026-02-18T05:14:47.161948Z","end":"2026-02-18T05:14:47.31851Z","steps":["trace[511031893] 'process raft request' (duration: 135.657621ms)","trace[511031893] 'compare' (duration: 20.819913ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:47.318901Z","caller":"traceutil/trace.go:171","msg":"trace[1701849229] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"106.482639ms","start":"2026-02-18T05:14:47.212403Z","end":"2026-02-18T05:14:47.318885Z","steps":["trace[1701849229] 'process raft request' (duration: 106.354596ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:47.608828Z","caller":"traceutil/trace.go:171","msg":"trace[333695316] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"127.459717ms","start":"2026-02-18T05:14:47.481347Z","end":"2026-02-18T05:14:47.608806Z","steps":["trace[333695316] 'process raft request' (duration: 122.298792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:48.261067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.24854ms","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-18T05:14:48.261162Z","caller":"traceutil/trace.go:171","msg":"trace[1754158059] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:642; }","duration":"202.446545ms","start":"2026-02-18T05:14:48.058695Z","end":"2026-02-18T05:14:48.261142Z","steps":["trace[1754158059] 'range keys from in-memory index tree' (duration: 202.066195ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:49.082852Z","caller":"traceutil/trace.go:171","msg":"trace[322692915] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"138.682976ms","start":"2026-02-18T05:14:48.944156Z","end":"2026-02-18T05:14:49.082839Z","steps":["trace[322692915] 'process raft request' (duration: 105.781374ms)","trace[322692915] 'compare' (duration: 32.81723ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:53.611725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.412991ms","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-18T05:14:53.611783Z","caller":"traceutil/trace.go:171","msg":"trace[1258143860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"124.536624ms","start":"2026-02-18T05:14:53.487235Z","end":"2026-02-18T05:14:53.611771Z","steps":["trace[1258143860] 'range keys from in-memory index tree' (duration: 124.245437ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:53.770838Z","caller":"traceutil/trace.go:171","msg":"trace[751475084] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"150.7071ms","start":"2026-02-18T05:14:53.620108Z","end":"2026-02-18T05:14:53.770815Z","steps":["trace[751475084] 'process raft request' (duration: 150.547586ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:53.770997Z","caller":"traceutil/trace.go:171","msg":"trace[623132198] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"138.597531ms","start":"2026-02-18T05:14:53.632386Z","end":"2026-02-18T05:14:53.770983Z","steps":["trace[623132198] 'process raft request' (duration: 138.359975ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:54.095594Z","caller":"traceutil/trace.go:171","msg":"trace[2104307265] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"186.884192ms","start":"2026-02-18T05:14:53.908695Z","end":"2026-02-18T05:14:54.095579Z","steps":["trace[2104307265] 'process raft request' (duration: 184.120255ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:54.719912Z","caller":"traceutil/trace.go:171","msg":"trace[1209036637] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"208.029427ms","start":"2026-02-18T05:14:54.511871Z","end":"2026-02-18T05:14:54.719901Z","steps":["trace[1209036637] 'process raft request' (duration: 207.949655ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:54.727672Z","caller":"traceutil/trace.go:171","msg":"trace[559822051] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"215.453539ms","start":"2026-02-18T05:14:54.51221Z","end":"2026-02-18T05:14:54.727663Z","steps":["trace[559822051] 'process raft request' (duration: 215.262474ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:55.188059Z","caller":"traceutil/trace.go:171","msg":"trace[588670351] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"119.424684ms","start":"2026-02-18T05:14:55.068609Z","end":"2026-02-18T05:14:55.188034Z","steps":["trace[588670351] 'process raft request' (duration: 119.304041ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:56.942638Z","caller":"traceutil/trace.go:171","msg":"trace[1317596276] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"136.425527ms","start":"2026-02-18T05:14:56.80619Z","end":"2026-02-18T05:14:56.942616Z","steps":["trace[1317596276] 'process raft request' (duration: 102.897502ms)","trace[1317596276] 'compare' (duration: 33.451693ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:57.391752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.683387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:57.392358Z","caller":"traceutil/trace.go:171","msg":"trace[1437593131] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:749; }","duration":"338.5596ms","start":"2026-02-18T05:14:57.053782Z","end":"2026-02-18T05:14:57.392341Z","steps":["trace[1437593131] 'read index received' (duration: 98.870114ms)","trace[1437593131] 'applied index is now lower than readState.Index' (duration: 239.688726ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:57.392917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.123363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-md8rg\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-18T05:14:57.393533Z","caller":"traceutil/trace.go:171","msg":"trace[1105867476] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-md8rg; range_end:; response_count:1; response_revision:730; }","duration":"339.750408ms","start":"2026-02-18T05:14:57.053763Z","end":"2026-02-18T05:14:57.393514Z","steps":["trace[1105867476] 'agreement among raft nodes before linearized reading' (duration: 338.805405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:57.393784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:57.053758Z","time spent":"339.985263ms","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4719,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-md8rg\" "} {"level":"info","ts":"2026-02-18T05:14:57.394672Z","caller":"traceutil/trace.go:171","msg":"trace[25972767] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"341.026698ms","start":"2026-02-18T05:14:57.053627Z","end":"2026-02-18T05:14:57.394654Z","steps":["trace[25972767] 'process raft request' (duration: 229.36695ms)","trace[25972767] 'compare' (duration: 108.573365ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:57.394946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:57.053609Z","time spent":"341.263734ms","remote":"127.0.0.1:42444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:57.39701Z","caller":"traceutil/trace.go:171","msg":"trace[1429571539] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"343.303003ms","start":"2026-02-18T05:14:57.05369Z","end":"2026-02-18T05:14:57.396993Z","steps":["trace[1429571539] 'process raft request' (duration: 338.58458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:57.397314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:57.053686Z","time spent":"343.557208ms","remote":"127.0.0.1:42162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":900,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:57.693337Z","caller":"traceutil/trace.go:171","msg":"trace[1358343859] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"285.276561ms","start":"2026-02-18T05:14:57.408045Z","end":"2026-02-18T05:14:57.693321Z","steps":["trace[1358343859] 'process raft request' (duration: 285.150958ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:58.030082Z","caller":"traceutil/trace.go:171","msg":"trace[1704363993] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"621.445717ms","start":"2026-02-18T05:14:57.408618Z","end":"2026-02-18T05:14:58.030064Z","steps":["trace[1704363993] 'process raft request' (duration: 621.263183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.030223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:57.408611Z","time spent":"621.52934ms","remote":"127.0.0.1:42444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:58.030764Z","caller":"traceutil/trace.go:171","msg":"trace[1570455149] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:753; }","duration":"333.518193ms","start":"2026-02-18T05:14:57.697235Z","end":"2026-02-18T05:14:58.030753Z","steps":["trace[1570455149] 'read index received' (duration: 333.514093ms)","trace[1570455149] 'applied index is now lower than readState.Index' (duration: 3.33µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:58.030883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.641666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-5ffx4\" ","response":"range_response_count:1 size:5026"} {"level":"info","ts":"2026-02-18T05:14:58.03091Z","caller":"traceutil/trace.go:171","msg":"trace[184015898] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-5ffx4; range_end:; response_count:1; response_revision:732; }","duration":"333.693478ms","start":"2026-02-18T05:14:57.697208Z","end":"2026-02-18T05:14:58.030901Z","steps":["trace[184015898] 'agreement among raft nodes before linearized reading' (duration: 333.597945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.030937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:57.697195Z","time spent":"333.736088ms","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5048,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-5ffx4\" "} {"level":"info","ts":"2026-02-18T05:14:58.252004Z","caller":"traceutil/trace.go:171","msg":"trace[182583565] transaction","detail":"{read_only:false; number_of_response:1; response_revision:733; }","duration":"551.713451ms","start":"2026-02-18T05:14:57.700279Z","end":"2026-02-18T05:14:58.251992Z","steps":["trace[182583565] 'process raft request' (duration: 551.538967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.253256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:57.700273Z","time spent":"552.93815ms","remote":"127.0.0.1:42444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56,"response count":0,"response size":3830,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:58.253474Z","caller":"traceutil/trace.go:171","msg":"trace[1040313227] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"554.149599ms","start":"2026-02-18T05:14:57.699317Z","end":"2026-02-18T05:14:58.253467Z","steps":["trace[1040313227] 'process raft request' (duration: 552.362457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.253549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:57.699308Z","time spent":"554.22358ms","remote":"127.0.0.1:42262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1612,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:58.257582Z","caller":"traceutil/trace.go:171","msg":"trace[760599184] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"554.709251ms","start":"2026-02-18T05:14:57.702856Z","end":"2026-02-18T05:14:58.257565Z","steps":["trace[760599184] 'process raft request' (duration: 549.092248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.257659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:57.702838Z","time spent":"554.792433ms","remote":"127.0.0.1:42162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1017,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:58.258102Z","caller":"traceutil/trace.go:171","msg":"trace[1562941001] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"517.116686ms","start":"2026-02-18T05:14:57.74098Z","end":"2026-02-18T05:14:58.258097Z","steps":["trace[1562941001] 'process raft request' (duration: 510.995222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.258583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:57.740963Z","time spent":"517.569946ms","remote":"127.0.0.1:42256","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-18T05:14:58.258993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.130272ms","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-18T05:14:58.259432Z","caller":"traceutil/trace.go:171","msg":"trace[306794894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"282.591983ms","start":"2026-02-18T05:14:57.976832Z","end":"2026-02-18T05:14:58.259424Z","steps":["trace[306794894] 'agreement among raft nodes before linearized reading' (duration: 282.111852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.259665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.667929ms","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-18T05:14:58.259714Z","caller":"traceutil/trace.go:171","msg":"trace[515191311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"462.727801ms","start":"2026-02-18T05:14:57.79698Z","end":"2026-02-18T05:14:58.259708Z","steps":["trace[515191311] 'agreement among raft nodes before linearized reading' (duration: 462.648809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.259745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:57.796974Z","time spent":"462.761352ms","remote":"127.0.0.1:42256","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-18T05:14:58.260399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.978807ms","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-18T05:14:58.260476Z","caller":"traceutil/trace.go:171","msg":"trace[158248728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:735; }","duration":"534.068439ms","start":"2026-02-18T05:14:57.726402Z","end":"2026-02-18T05:14:58.260471Z","steps":["trace[158248728] 'agreement among raft nodes before linearized reading' (duration: 533.949026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.260524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:57.726396Z","time spent":"534.12156ms","remote":"127.0.0.1:42256","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-18T05:14:58.260319Z","caller":"traceutil/trace.go:171","msg":"trace[211605657] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:753; }","duration":"227.291223ms","start":"2026-02-18T05:14:58.03079Z","end":"2026-02-18T05:14:58.258081Z","steps":["trace[211605657] 'read index received' (duration: 220.83427ms)","trace[211605657] 'applied index is now lower than readState.Index' (duration: 6.456473ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:58.306193Z","caller":"traceutil/trace.go:171","msg":"trace[1558585151] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"265.660058ms","start":"2026-02-18T05:14:58.040519Z","end":"2026-02-18T05:14:58.306179Z","steps":["trace[1558585151] 'process raft request' (duration: 265.613926ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:58.306724Z","caller":"traceutil/trace.go:171","msg":"trace[2058158702] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"269.294123ms","start":"2026-02-18T05:14:58.037423Z","end":"2026-02-18T05:14:58.306717Z","steps":["trace[2058158702] 'process raft request' (duration: 268.534735ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:58.306957Z","caller":"traceutil/trace.go:171","msg":"trace[1212785000] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"269.354905ms","start":"2026-02-18T05:14:58.037595Z","end":"2026-02-18T05:14:58.30695Z","steps":["trace[1212785000] 'process raft request' (duration: 268.482254ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:58.452461Z","caller":"traceutil/trace.go:171","msg":"trace[1462843936] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"189.339177ms","start":"2026-02-18T05:14:58.263113Z","end":"2026-02-18T05:14:58.452452Z","steps":["trace[1462843936] 'process raft request' (duration: 102.302973ms)","trace[1462843936] 'compare' (duration: 86.585263ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:58.452894Z","caller":"traceutil/trace.go:171","msg":"trace[56546331] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"186.112541ms","start":"2026-02-18T05:14:58.266776Z","end":"2026-02-18T05:14:58.452888Z","steps":["trace[56546331] 'process raft request' (duration: 185.418205ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:58.452228Z","caller":"traceutil/trace.go:171","msg":"trace[886326750] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:760; }","duration":"189.02649ms","start":"2026-02-18T05:14:58.263187Z","end":"2026-02-18T05:14:58.452214Z","steps":["trace[886326750] 'read index received' (duration: 48.659498ms)","trace[886326750] 'applied index is now lower than readState.Index' (duration: 140.366522ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:58.454138Z","caller":"traceutil/trace.go:171","msg":"trace[542084017] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"190.969806ms","start":"2026-02-18T05:14:58.263161Z","end":"2026-02-18T05:14:58.454131Z","steps":["trace[542084017] 'process raft request' (duration: 189.008629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.454236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.888288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:58.454382Z","caller":"traceutil/trace.go:171","msg":"trace[646956363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"256.175734ms","start":"2026-02-18T05:14:58.198198Z","end":"2026-02-18T05:14:58.454374Z","steps":["trace[646956363] 'agreement among raft nodes before linearized reading' (duration: 255.891248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.454554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.544238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-18T05:14:58.456297Z","caller":"traceutil/trace.go:171","msg":"trace[1837608443] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:741; }","duration":"198.300669ms","start":"2026-02-18T05:14:58.257987Z","end":"2026-02-18T05:14:58.456288Z","steps":["trace[1837608443] 'agreement among raft nodes before linearized reading' (duration: 196.513526ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:58.473713Z","caller":"traceutil/trace.go:171","msg":"trace[1738240842] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"141.859557ms","start":"2026-02-18T05:14:58.331844Z","end":"2026-02-18T05:14:58.473704Z","steps":["trace[1738240842] 'process raft request' (duration: 141.796756ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:58.473874Z","caller":"traceutil/trace.go:171","msg":"trace[1865455792] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"141.950879ms","start":"2026-02-18T05:14:58.331917Z","end":"2026-02-18T05:14:58.473868Z","steps":["trace[1865455792] 'process raft request' (duration: 141.744884ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:58.473981Z","caller":"traceutil/trace.go:171","msg":"trace[317420126] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"142.001201ms","start":"2026-02-18T05:14:58.331975Z","end":"2026-02-18T05:14:58.473976Z","steps":["trace[317420126] 'process raft request' (duration: 141.708534ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:58.474197Z","caller":"traceutil/trace.go:171","msg":"trace[1725735846] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"142.727398ms","start":"2026-02-18T05:14:58.331465Z","end":"2026-02-18T05:14:58.474193Z","steps":["trace[1725735846] 'process raft request' (duration: 142.120223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.735501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.782145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:58.735588Z","caller":"traceutil/trace.go:171","msg":"trace[214355527] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"275.92475ms","start":"2026-02-18T05:14:58.459648Z","end":"2026-02-18T05:14:58.735572Z","steps":["trace[214355527] 'process raft request' (duration: 148.004622ms)","trace[214355527] 'compare' (duration: 127.680542ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:58.797381Z","caller":"traceutil/trace.go:171","msg":"trace[1818721063] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:767; }","duration":"269.100729ms","start":"2026-02-18T05:14:58.528266Z","end":"2026-02-18T05:14:58.797367Z","steps":["trace[1818721063] 'read index received' (duration: 79.392593ms)","trace[1818721063] 'applied index is now lower than readState.Index' (duration: 189.707496ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:58.797666Z","caller":"traceutil/trace.go:171","msg":"trace[352913835] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"311.49116ms","start":"2026-02-18T05:14:58.486167Z","end":"2026-02-18T05:14:58.797658Z","steps":["trace[352913835] 'process raft request' (duration: 311.110551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.79864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:58.486152Z","time spent":"312.448492ms","remote":"127.0.0.1:42420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:14:58.797916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.641453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T05:14:58.798742Z","caller":"traceutil/trace.go:171","msg":"trace[77202562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"270.495262ms","start":"2026-02-18T05:14:58.528241Z","end":"2026-02-18T05:14:58.798736Z","steps":["trace[77202562] 'agreement among raft nodes before linearized reading' (duration: 269.602111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:58.797964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.849092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:14:58.79882Z","caller":"traceutil/trace.go:171","msg":"trace[1508376885] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:747; }","duration":"110.731542ms","start":"2026-02-18T05:14:58.688083Z","end":"2026-02-18T05:14:58.798815Z","steps":["trace[1508376885] 'agreement among raft nodes before linearized reading' (duration: 109.854852ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:59.182282Z","caller":"traceutil/trace.go:171","msg":"trace[113247332] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"378.956125ms","start":"2026-02-18T05:14:58.803314Z","end":"2026-02-18T05:14:59.182271Z","steps":["trace[113247332] 'process raft request' (duration: 378.788531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:59.18237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:58.803301Z","time spent":"379.017936ms","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4806,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:59.211855Z","caller":"traceutil/trace.go:171","msg":"trace[1477032411] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"401.808527ms","start":"2026-02-18T05:14:58.810034Z","end":"2026-02-18T05:14:59.211843Z","steps":["trace[1477032411] 'process raft request' (duration: 401.702895ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:59.212058Z","caller":"traceutil/trace.go:171","msg":"trace[1302486600] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"392.210451ms","start":"2026-02-18T05:14:58.819828Z","end":"2026-02-18T05:14:59.212038Z","steps":["trace[1302486600] 'process raft request' (duration: 391.993546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:59.21299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:58.819813Z","time spent":"393.144253ms","remote":"127.0.0.1:42420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:14:59.213027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:58.810021Z","time spent":"402.905564ms","remote":"127.0.0.1:42256","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-18T05:14:59.21226Z","caller":"traceutil/trace.go:171","msg":"trace[1961971432] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"402.165656ms","start":"2026-02-18T05:14:58.81009Z","end":"2026-02-18T05:14:59.212256Z","steps":["trace[1961971432] 'process raft request' (duration: 401.711145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:59.213111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:58.810085Z","time spent":"403.012856ms","remote":"127.0.0.1:42256","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-18T05:14:59.597321Z","caller":"traceutil/trace.go:171","msg":"trace[138970393] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"226.18015ms","start":"2026-02-18T05:14:59.371126Z","end":"2026-02-18T05:14:59.597306Z","steps":["trace[138970393] 'process raft request' (duration: 226.104668ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:59.95639Z","caller":"traceutil/trace.go:171","msg":"trace[1424275804] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"139.190546ms","start":"2026-02-18T05:14:59.817176Z","end":"2026-02-18T05:14:59.956367Z","steps":["trace[1424275804] 'process raft request' (duration: 139.043332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:59.9566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.322359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-md8rg\" ","response":"range_response_count:1 size:4821"} {"level":"info","ts":"2026-02-18T05:14:59.956643Z","caller":"traceutil/trace.go:171","msg":"trace[248056842] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-md8rg; range_end:; response_count:1; response_revision:758; }","duration":"139.38036ms","start":"2026-02-18T05:14:59.817253Z","end":"2026-02-18T05:14:59.956633Z","steps":["trace[248056842] 'agreement among raft nodes before linearized reading' (duration: 139.175655ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:59.956382Z","caller":"traceutil/trace.go:171","msg":"trace[141321750] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"139.101064ms","start":"2026-02-18T05:14:59.817259Z","end":"2026-02-18T05:14:59.95636Z","steps":["trace[141321750] 'read index received' (duration: 138.876208ms)","trace[141321750] 'applied index is now lower than readState.Index' (duration: 223.866µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:15:00.592288Z","caller":"traceutil/trace.go:171","msg":"trace[1612359267] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"124.930447ms","start":"2026-02-18T05:15:00.467336Z","end":"2026-02-18T05:15:00.592267Z","steps":["trace[1612359267] 'process raft request' (duration: 124.810644ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:15:00.746176Z","caller":"traceutil/trace.go:171","msg":"trace[1159771033] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"278.202529ms","start":"2026-02-18T05:15:00.467959Z","end":"2026-02-18T05:15:00.746161Z","steps":["trace[1159771033] 'process raft request' (duration: 270.377788ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:15:00.746272Z","caller":"traceutil/trace.go:171","msg":"trace[839582539] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"276.620143ms","start":"2026-02-18T05:15:00.469647Z","end":"2026-02-18T05:15:00.746267Z","steps":["trace[839582539] 'process raft request' (duration: 276.292385ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:15:01.063878Z","caller":"traceutil/trace.go:171","msg":"trace[379131236] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"140.27972ms","start":"2026-02-18T05:15:00.923584Z","end":"2026-02-18T05:15:01.063863Z","steps":["trace[379131236] 'process raft request' (duration: 140.160997ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:15:02.732174Z","caller":"traceutil/trace.go:171","msg":"trace[1068383993] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"133.473201ms","start":"2026-02-18T05:15:02.598676Z","end":"2026-02-18T05:15:02.732149Z","steps":["trace[1068383993] 'process raft request' (duration: 133.319748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:15:03.037079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.063597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:15:03.037179Z","caller":"traceutil/trace.go:171","msg":"trace[1773603652] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"281.16365ms","start":"2026-02-18T05:15:02.756006Z","end":"2026-02-18T05:15:03.03717Z","steps":["trace[1773603652] 'process raft request' (duration: 281.136779ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:15:03.037371Z","caller":"traceutil/trace.go:171","msg":"trace[1230201963] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"283.349479ms","start":"2026-02-18T05:15:02.754015Z","end":"2026-02-18T05:15:03.037365Z","steps":["trace[1230201963] 'process raft request' (duration: 123.948304ms)","trace[1230201963] 'compare' (duration: 158.997156ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:15:03.18936Z","caller":"traceutil/trace.go:171","msg":"trace[710480056] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"137.687331ms","start":"2026-02-18T05:15:03.050099Z","end":"2026-02-18T05:15:03.187787Z","steps":["trace[710480056] 'process raft request' (duration: 137.138268ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:15:05.289296Z","caller":"traceutil/trace.go:171","msg":"trace[190843363] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"166.777644ms","start":"2026-02-18T05:15:05.122503Z","end":"2026-02-18T05:15:05.289281Z","steps":["trace[190843363] 'process raft request' (duration: 160.413493ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:15:09.649623Z","caller":"traceutil/trace.go:171","msg":"trace[874349201] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"145.911345ms","start":"2026-02-18T05:15:09.503697Z","end":"2026-02-18T05:15:09.649609Z","steps":["trace[874349201] 'process raft request' (duration: 126.349985ms)","trace[874349201] 'compare' (duration: 19.328715ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:15:11.807815Z","caller":"traceutil/trace.go:171","msg":"trace[495052067] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"136.003945ms","start":"2026-02-18T05:15:11.671796Z","end":"2026-02-18T05:15:11.8078Z","steps":["trace[495052067] 'process raft request' (duration: 135.911893ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:15:18.933307Z","caller":"traceutil/trace.go:171","msg":"trace[215318539] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"121.357638ms","start":"2026-02-18T05:15:18.811936Z","end":"2026-02-18T05:15:18.933294Z","steps":["trace[215318539] 'process raft request' (duration: 121.114793ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:15:19.040148Z","caller":"traceutil/trace.go:171","msg":"trace[1215528141] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"102.590148ms","start":"2026-02-18T05:15:18.937538Z","end":"2026-02-18T05:15:19.040128Z","steps":["trace[1215528141] 'process raft request' (duration: 23.47064ms)","trace[1215528141] 'compare' (duration: 79.011865ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:15:33.818526Z","caller":"traceutil/trace.go:171","msg":"trace[1446871897] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"230.146367ms","start":"2026-02-18T05:15:33.588354Z","end":"2026-02-18T05:15:33.8185Z","steps":["trace[1446871897] 'process raft request' (duration: 229.983324ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:15:34.048784Z","caller":"traceutil/trace.go:171","msg":"trace[683832335] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"308.803364ms","start":"2026-02-18T05:15:33.739956Z","end":"2026-02-18T05:15:34.04876Z","steps":["trace[683832335] 'read index received' (duration: 78.600633ms)","trace[683832335] 'applied index is now lower than readState.Index' (duration: 230.201281ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:15:34.048905Z","caller":"traceutil/trace.go:171","msg":"trace[1351707901] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"386.801636ms","start":"2026-02-18T05:15:33.662094Z","end":"2026-02-18T05:15:34.048895Z","steps":["trace[1351707901] 'process raft request' (duration: 339.261607ms)","trace[1351707901] 'compare' (duration: 47.284654ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:15:34.049007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:15:33.662067Z","time spent":"386.857057ms","remote":"127.0.0.1:42176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4941,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:15:34.049195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.246623ms","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-18T05:15:34.049213Z","caller":"traceutil/trace.go:171","msg":"trace[1724389115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:865; }","duration":"309.302163ms","start":"2026-02-18T05:15:33.739904Z","end":"2026-02-18T05:15:34.049206Z","steps":["trace[1724389115] 'agreement among raft nodes before linearized reading' (duration: 309.205962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:15:34.049228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:15:33.739883Z","time spent":"309.341785ms","remote":"127.0.0.1:42256","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-18T05:15:34.049435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.063571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:15:34.04945Z","caller":"traceutil/trace.go:171","msg":"trace[171034870] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:865; }","duration":"185.112932ms","start":"2026-02-18T05:15:33.864332Z","end":"2026-02-18T05:15:34.049445Z","steps":["trace[171034870] 'agreement among raft nodes before linearized reading' (duration: 185.079631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:15:34.049576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.968077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T05:15:34.049592Z","caller":"traceutil/trace.go:171","msg":"trace[256788680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:865; }","duration":"102.031728ms","start":"2026-02-18T05:15:33.947553Z","end":"2026-02-18T05:15:34.049585Z","steps":["trace[256788680] 'agreement among raft nodes before linearized reading' (duration: 101.984477ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:15:53.908781Z","caller":"traceutil/trace.go:171","msg":"trace[744803054] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"172.806018ms","start":"2026-02-18T05:15:53.735954Z","end":"2026-02-18T05:15:53.90876Z","steps":["trace[744803054] 'process raft request' (duration: 172.686986ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:00.930593Z","caller":"traceutil/trace.go:171","msg":"trace[87284384] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"110.244ms","start":"2026-02-18T05:16:00.82031Z","end":"2026-02-18T05:16:00.930554Z","steps":["trace[87284384] 'process raft request' (duration: 110.039916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:01.17702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.07986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:16:01.177313Z","caller":"traceutil/trace.go:171","msg":"trace[1604789976] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1005; }","duration":"177.645528ms","start":"2026-02-18T05:16:00.999624Z","end":"2026-02-18T05:16:01.177269Z","steps":["trace[1604789976] 'read index received' (duration: 647.16µs)","trace[1604789976] 'applied index is now lower than readState.Index' (duration: 176.997028ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:16:01.177567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.953483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-18T05:16:01.1776Z","caller":"traceutil/trace.go:171","msg":"trace[551141649] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:970; }","duration":"178.050234ms","start":"2026-02-18T05:16:00.999537Z","end":"2026-02-18T05:16:01.177587Z","steps":["trace[551141649] 'agreement among raft nodes before linearized reading' (duration: 177.811941ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:01.177729Z","caller":"traceutil/trace.go:171","msg":"trace[388899475] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"236.717804ms","start":"2026-02-18T05:16:00.940976Z","end":"2026-02-18T05:16:01.177694Z","steps":["trace[388899475] 'process raft request' (duration: 58.870313ms)","trace[388899475] 'compare' (duration: 176.910507ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:16:01.177974Z","caller":"traceutil/trace.go:171","msg":"trace[141409775] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"227.205938ms","start":"2026-02-18T05:16:00.950751Z","end":"2026-02-18T05:16:01.177957Z","steps":["trace[141409775] 'process raft request' (duration: 226.439826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:01.403792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.485631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-18T05:16:01.404119Z","caller":"traceutil/trace.go:171","msg":"trace[276789039] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:970; }","duration":"119.792906ms","start":"2026-02-18T05:16:01.28416Z","end":"2026-02-18T05:16:01.403953Z","steps":["trace[276789039] 'range keys from in-memory index tree' (duration: 119.37271ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:03.94883Z","caller":"traceutil/trace.go:171","msg":"trace[716189184] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"101.589574ms","start":"2026-02-18T05:16:03.847207Z","end":"2026-02-18T05:16:03.948797Z","steps":["trace[716189184] 'read index received' (duration: 101.299779ms)","trace[716189184] 'applied index is now lower than readState.Index' (duration: 288.485µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:16:03.948994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.743576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:16:03.949053Z","caller":"traceutil/trace.go:171","msg":"trace[75569493] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:979; }","duration":"101.869538ms","start":"2026-02-18T05:16:03.847151Z","end":"2026-02-18T05:16:03.949021Z","steps":["trace[75569493] 'agreement among raft nodes before linearized reading' (duration: 101.766267ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:03.949283Z","caller":"traceutil/trace.go:171","msg":"trace[114691174] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"443.340343ms","start":"2026-02-18T05:16:03.505879Z","end":"2026-02-18T05:16:03.949219Z","steps":["trace[114691174] 'process raft request' (duration: 442.706664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:03.949522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:03.505863Z","time spent":"443.514496ms","remote":"127.0.0.1:42256","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-18T05:16:12.534947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408501543179227,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:16:12.987166Z","caller":"traceutil/trace.go:171","msg":"trace[1045041838] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"952.679381ms","start":"2026-02-18T05:16:12.034461Z","end":"2026-02-18T05:16:12.98714Z","steps":["trace[1045041838] 'read index received' (duration: 952.60586ms)","trace[1045041838] 'applied index is now lower than readState.Index' (duration: 72.211µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:16:12.987302Z","caller":"traceutil/trace.go:171","msg":"trace[2009854193] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"999.666783ms","start":"2026-02-18T05:16:11.987624Z","end":"2026-02-18T05:16:12.987291Z","steps":["trace[2009854193] 'process raft request' (duration: 999.338798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:12.987496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:11.987604Z","time spent":"999.734273ms","remote":"127.0.0.1:42256","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-18T05:16:12.987843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.622591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T05:16:12.99258Z","caller":"traceutil/trace.go:171","msg":"trace[72314721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"816.39761ms","start":"2026-02-18T05:16:12.176163Z","end":"2026-02-18T05:16:12.992561Z","steps":["trace[72314721] 'agreement among raft nodes before linearized reading' (duration: 811.55038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:12.992718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:12.176139Z","time spent":"816.548563ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:16:12.988117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.469181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T05:16:12.993068Z","caller":"traceutil/trace.go:171","msg":"trace[46827042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"418.414342ms","start":"2026-02-18T05:16:12.57461Z","end":"2026-02-18T05:16:12.993025Z","steps":["trace[46827042] 'agreement among raft nodes before linearized reading' (duration: 413.296949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:12.993242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:12.574599Z","time spent":"418.631185ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:16:12.988224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.766697ms","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-18T05:16:12.993475Z","caller":"traceutil/trace.go:171","msg":"trace[170668870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"959.038683ms","start":"2026-02-18T05:16:12.034411Z","end":"2026-02-18T05:16:12.993449Z","steps":["trace[170668870] 'agreement among raft nodes before linearized reading' (duration: 953.737206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:12.99364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:12.034392Z","time spent":"959.231386ms","remote":"127.0.0.1:42256","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":"warn","ts":"2026-02-18T05:16:12.988557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.88198ms","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-18T05:16:12.993786Z","caller":"traceutil/trace.go:171","msg":"trace[2043277384] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"424.158395ms","start":"2026-02-18T05:16:12.569616Z","end":"2026-02-18T05:16:12.993774Z","steps":["trace[2043277384] 'agreement among raft nodes before linearized reading' (duration: 418.876419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:12.993849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:12.569588Z","time spent":"424.250337ms","remote":"127.0.0.1:42256","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-18T05:16:12.988618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.956005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:16:12.993969Z","caller":"traceutil/trace.go:171","msg":"trace[1794971182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"792.329482ms","start":"2026-02-18T05:16:12.20163Z","end":"2026-02-18T05:16:12.993959Z","steps":["trace[1794971182] 'agreement among raft nodes before linearized reading' (duration: 786.963464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:12.994049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:12.201617Z","time spent":"792.422913ms","remote":"127.0.0.1:42016","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-18T05:16:13.107119Z","caller":"traceutil/trace.go:171","msg":"trace[715031354] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"100.210533ms","start":"2026-02-18T05:16:13.006891Z","end":"2026-02-18T05:16:13.107101Z","steps":["trace[715031354] 'process raft request' (duration: 99.782277ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:13.107247Z","caller":"traceutil/trace.go:171","msg":"trace[1745605391] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"100.292904ms","start":"2026-02-18T05:16:13.006945Z","end":"2026-02-18T05:16:13.107238Z","steps":["trace[1745605391] 'process raft request' (duration: 99.838257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:27.712137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.391482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:16:27.712941Z","caller":"traceutil/trace.go:171","msg":"trace[703154628] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"481.74515ms","start":"2026-02-18T05:16:27.231124Z","end":"2026-02-18T05:16:27.712869Z","steps":["trace[703154628] 'read index received' (duration: 114.361814ms)","trace[703154628] 'applied index is now lower than readState.Index' (duration: 367.380596ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:16:27.713409Z","caller":"traceutil/trace.go:171","msg":"trace[288976946] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"506.902772ms","start":"2026-02-18T05:16:27.206489Z","end":"2026-02-18T05:16:27.713391Z","steps":["trace[288976946] 'process raft request' (duration: 139.101721ms)","trace[288976946] 'compare' (duration: 366.004236ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:16:27.713548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:27.206477Z","time spent":"506.974153ms","remote":"127.0.0.1:42256","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-18T05:16:27.713916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.228612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-18T05:16:27.714165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.72517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" ","response":"range_response_count:1 size:739"} {"level":"info","ts":"2026-02-18T05:16:27.714217Z","caller":"traceutil/trace.go:171","msg":"trace[1571526679] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:route-controller; range_end:; response_count:1; response_revision:1054; }","duration":"406.814902ms","start":"2026-02-18T05:16:27.307382Z","end":"2026-02-18T05:16:27.714197Z","steps":["trace[1571526679] 'agreement among raft nodes before linearized reading' (duration: 406.749721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:27.714275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:27.307358Z","time spent":"406.909003ms","remote":"127.0.0.1:42318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":761,"request content":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" "} {"level":"info","ts":"2026-02-18T05:16:27.71449Z","caller":"traceutil/trace.go:171","msg":"trace[90527646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1054; }","duration":"475.525505ms","start":"2026-02-18T05:16:27.238649Z","end":"2026-02-18T05:16:27.714175Z","steps":["trace[90527646] 'agreement among raft nodes before linearized reading' (duration: 475.091989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:27.713957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.998346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-scheduler\" ","response":"range_response_count:1 size:1383"} {"level":"info","ts":"2026-02-18T05:16:27.716551Z","caller":"traceutil/trace.go:171","msg":"trace[2069573975] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"368.622212ms","start":"2026-02-18T05:16:27.347905Z","end":"2026-02-18T05:16:27.716527Z","steps":["trace[2069573975] 'agreement among raft nodes before linearized reading' (duration: 365.995767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:27.71684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:27.347882Z","time spent":"368.939436ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":1405,"request content":"key:\"/registry/clusterroles/system:kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:16:27.7141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.984117ms","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-18T05:16:27.719015Z","caller":"traceutil/trace.go:171","msg":"trace[2004433443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1054; }","duration":"487.896464ms","start":"2026-02-18T05:16:27.231079Z","end":"2026-02-18T05:16:27.718975Z","steps":["trace[2004433443] 'agreement among raft nodes before linearized reading' (duration: 482.910616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:27.719149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:27.231062Z","time spent":"488.034315ms","remote":"127.0.0.1:42256","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":"warn","ts":"2026-02-18T05:16:27.720147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:16:27.238639Z","time spent":"477.637074ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T05:16:31.842485Z","caller":"traceutil/trace.go:171","msg":"trace[138077693] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"111.484363ms","start":"2026-02-18T05:16:31.730944Z","end":"2026-02-18T05:16:31.842429Z","steps":["trace[138077693] 'process raft request' (duration: 111.27199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:33.569105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.977775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/default/\" range_end:\"/registry/networkpolicies/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:16:33.569202Z","caller":"traceutil/trace.go:171","msg":"trace[2030111824] range","detail":"{range_begin:/registry/networkpolicies/default/; range_end:/registry/networkpolicies/default0; response_count:0; response_revision:1070; }","duration":"126.158968ms","start":"2026-02-18T05:16:33.443022Z","end":"2026-02-18T05:16:33.569181Z","steps":["trace[2030111824] 'range keys from in-memory index tree' (duration: 125.877934ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:34.112237Z","caller":"traceutil/trace.go:171","msg":"trace[670767736] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"182.839468ms","start":"2026-02-18T05:16:33.929375Z","end":"2026-02-18T05:16:34.112214Z","steps":["trace[670767736] 'process raft request' (duration: 182.712686ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:34.159686Z","caller":"traceutil/trace.go:171","msg":"trace[417942769] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1115; }","duration":"199.244574ms","start":"2026-02-18T05:16:33.960423Z","end":"2026-02-18T05:16:34.159668Z","steps":["trace[417942769] 'read index received' (duration: 152.294201ms)","trace[417942769] 'applied index is now lower than readState.Index' (duration: 46.949783ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:16:34.1599Z","caller":"traceutil/trace.go:171","msg":"trace[86158270] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"230.341977ms","start":"2026-02-18T05:16:33.929551Z","end":"2026-02-18T05:16:34.159893Z","steps":["trace[86158270] 'process raft request' (duration: 230.019463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:34.160077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.622208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/default\" ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-02-18T05:16:34.160101Z","caller":"traceutil/trace.go:171","msg":"trace[1817617946] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/default; range_end:; response_count:1; response_revision:1073; }","duration":"199.698769ms","start":"2026-02-18T05:16:33.960396Z","end":"2026-02-18T05:16:34.160095Z","steps":["trace[1817617946] 'agreement among raft nodes before linearized reading' (duration: 199.614328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:34.160204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.912175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/kube-node-lease/\" range_end:\"/registry/statefulsets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:16:34.160219Z","caller":"traceutil/trace.go:171","msg":"trace[1195451346] range","detail":"{range_begin:/registry/statefulsets/kube-node-lease/; range_end:/registry/statefulsets/kube-node-lease0; response_count:0; response_revision:1073; }","duration":"133.948726ms","start":"2026-02-18T05:16:34.026265Z","end":"2026-02-18T05:16:34.160213Z","steps":["trace[1195451346] 'agreement among raft nodes before linearized reading' (duration: 133.921525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:34.426077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.58797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-18T05:16:34.426162Z","caller":"traceutil/trace.go:171","msg":"trace[587170074] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:1; response_revision:1073; }","duration":"200.716501ms","start":"2026-02-18T05:16:34.225425Z","end":"2026-02-18T05:16:34.426142Z","steps":["trace[587170074] 'range keys from in-memory index tree' (duration: 200.379917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:34.426388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.354836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/default\" ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-02-18T05:16:34.426425Z","caller":"traceutil/trace.go:171","msg":"trace[647440805] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/default; range_end:; response_count:1; response_revision:1073; }","duration":"157.418627ms","start":"2026-02-18T05:16:34.268998Z","end":"2026-02-18T05:16:34.426416Z","steps":["trace[647440805] 'range keys from in-memory index tree' (duration: 157.294155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:34.426585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.234571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:16:34.426616Z","caller":"traceutil/trace.go:171","msg":"trace[118724430] range","detail":"{range_begin:/registry/rolebindings/default/; range_end:/registry/rolebindings/default0; response_count:0; response_revision:1073; }","duration":"122.286381ms","start":"2026-02-18T05:16:34.304317Z","end":"2026-02-18T05:16:34.426604Z","steps":["trace[118724430] 'range keys from in-memory index tree' (duration: 122.18732ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:36.033017Z","caller":"traceutil/trace.go:171","msg":"trace[2107610503] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"136.589343ms","start":"2026-02-18T05:16:35.896402Z","end":"2026-02-18T05:16:36.032991Z","steps":["trace[2107610503] 'process raft request' (duration: 136.294499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:36.456847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.722236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"info","ts":"2026-02-18T05:16:36.457562Z","caller":"traceutil/trace.go:171","msg":"trace[1384728632] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1079; }","duration":"142.491305ms","start":"2026-02-18T05:16:36.31505Z","end":"2026-02-18T05:16:36.457542Z","steps":["trace[1384728632] 'range keys from in-memory index tree' (duration: 141.589874ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:40.307595Z","caller":"traceutil/trace.go:171","msg":"trace[1208555916] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"197.707809ms","start":"2026-02-18T05:16:40.109855Z","end":"2026-02-18T05:16:40.307563Z","steps":["trace[1208555916] 'read index received' (duration: 121.065914ms)","trace[1208555916] 'applied index is now lower than readState.Index' (duration: 76.640375ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:16:40.30786Z","caller":"traceutil/trace.go:171","msg":"trace[1021856934] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"208.824533ms","start":"2026-02-18T05:16:40.098973Z","end":"2026-02-18T05:16:40.307798Z","steps":["trace[1021856934] 'process raft request' (duration: 131.924324ms)","trace[1021856934] 'compare' (duration: 76.495704ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:16:40.308099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.224076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-h66k7\" ","response":"range_response_count:1 size:4415"} {"level":"info","ts":"2026-02-18T05:16:40.30829Z","caller":"traceutil/trace.go:171","msg":"trace[218397254] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-h66k7; range_end:; response_count:1; response_revision:1089; }","duration":"198.461408ms","start":"2026-02-18T05:16:40.10982Z","end":"2026-02-18T05:16:40.308281Z","steps":["trace[218397254] 'agreement among raft nodes before linearized reading' (duration: 198.127914ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:40.384423Z","caller":"traceutil/trace.go:171","msg":"trace[1430726353] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"174.76524ms","start":"2026-02-18T05:16:40.209638Z","end":"2026-02-18T05:16:40.384403Z","steps":["trace[1430726353] 'process raft request' (duration: 174.518957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:40.3846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.021078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:16:40.384623Z","caller":"traceutil/trace.go:171","msg":"trace[940426151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"183.086438ms","start":"2026-02-18T05:16:40.20153Z","end":"2026-02-18T05:16:40.384616Z","steps":["trace[940426151] 'agreement among raft nodes before linearized reading' (duration: 183.041748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:40.384812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.904095ms","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-18T05:16:40.384832Z","caller":"traceutil/trace.go:171","msg":"trace[235019104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"185.946846ms","start":"2026-02-18T05:16:40.198879Z","end":"2026-02-18T05:16:40.384826Z","steps":["trace[235019104] 'agreement among raft nodes before linearized reading' (duration: 185.861035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:40.384947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.488985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T05:16:40.384981Z","caller":"traceutil/trace.go:171","msg":"trace[935510382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"187.553277ms","start":"2026-02-18T05:16:40.197405Z","end":"2026-02-18T05:16:40.384958Z","steps":["trace[935510382] 'agreement among raft nodes before linearized reading' (duration: 187.500726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:40.38508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.095954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6688"} {"level":"info","ts":"2026-02-18T05:16:40.385106Z","caller":"traceutil/trace.go:171","msg":"trace[1464448527] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:1090; }","duration":"265.135254ms","start":"2026-02-18T05:16:40.119963Z","end":"2026-02-18T05:16:40.385098Z","steps":["trace[1464448527] 'agreement among raft nodes before linearized reading' (duration: 265.092624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:40.570926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.653694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:16:40.571566Z","caller":"traceutil/trace.go:171","msg":"trace[435680067] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1135; }","duration":"179.413869ms","start":"2026-02-18T05:16:40.39213Z","end":"2026-02-18T05:16:40.571544Z","steps":["trace[435680067] 'read index received' (duration: 56.086217ms)","trace[435680067] 'applied index is now lower than readState.Index' (duration: 123.326422ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:16:40.571883Z","caller":"traceutil/trace.go:171","msg":"trace[1904301480] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"180.058088ms","start":"2026-02-18T05:16:40.39181Z","end":"2026-02-18T05:16:40.571868Z","steps":["trace[1904301480] 'process raft request' (duration: 179.607792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:40.572107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.868592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-h66k7\" ","response":"range_response_count:1 size:4415"} {"level":"info","ts":"2026-02-18T05:16:40.57216Z","caller":"traceutil/trace.go:171","msg":"trace[2131686579] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-h66k7; range_end:; response_count:1; response_revision:1092; }","duration":"178.982864ms","start":"2026-02-18T05:16:40.393167Z","end":"2026-02-18T05:16:40.57215Z","steps":["trace[2131686579] 'agreement among raft nodes before linearized reading' (duration: 178.786742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:40.572441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.302171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6688"} {"level":"info","ts":"2026-02-18T05:16:40.572485Z","caller":"traceutil/trace.go:171","msg":"trace[695915215] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:1092; }","duration":"180.359442ms","start":"2026-02-18T05:16:40.392115Z","end":"2026-02-18T05:16:40.572474Z","steps":["trace[695915215] 'agreement among raft nodes before linearized reading' (duration: 180.281441ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:40.571885Z","caller":"traceutil/trace.go:171","msg":"trace[1119718549] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"181.336965ms","start":"2026-02-18T05:16:40.390516Z","end":"2026-02-18T05:16:40.571853Z","steps":["trace[1119718549] 'process raft request' (duration: 57.633137ms)","trace[1119718549] 'compare' (duration: 121.233805ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:16:46.258658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.408545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-18T05:16:46.258735Z","caller":"traceutil/trace.go:171","msg":"trace[746681668] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1105; }","duration":"116.524786ms","start":"2026-02-18T05:16:46.142192Z","end":"2026-02-18T05:16:46.258717Z","steps":["trace[746681668] 'range keys from in-memory index tree' (duration: 116.308903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:46.259292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.123002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-18T05:16:46.259325Z","caller":"traceutil/trace.go:171","msg":"trace[334714884] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:1105; }","duration":"171.205223ms","start":"2026-02-18T05:16:46.08811Z","end":"2026-02-18T05:16:46.259315Z","steps":["trace[334714884] 'range keys from in-memory index tree' (duration: 171.01179ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:16:46.737839Z","caller":"traceutil/trace.go:171","msg":"trace[635429299] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"127.893131ms","start":"2026-02-18T05:16:46.60991Z","end":"2026-02-18T05:16:46.737804Z","steps":["trace[635429299] 'read index received' (duration: 127.318864ms)","trace[635429299] 'applied index is now lower than readState.Index' (duration: 572.497µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:16:46.738027Z","caller":"traceutil/trace.go:171","msg":"trace[1626154685] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"129.35251ms","start":"2026-02-18T05:16:46.608661Z","end":"2026-02-18T05:16:46.738013Z","steps":["trace[1626154685] 'process raft request' (duration: 128.486569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:46.738876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.933254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:89 size:65519"} {"level":"info","ts":"2026-02-18T05:16:46.738953Z","caller":"traceutil/trace.go:171","msg":"trace[677263378] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:89; response_revision:1107; }","duration":"129.055255ms","start":"2026-02-18T05:16:46.609884Z","end":"2026-02-18T05:16:46.738939Z","steps":["trace[677263378] 'agreement among raft nodes before linearized reading' (duration: 128.316936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:48.200707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.36096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-18T05:16:48.201184Z","caller":"traceutil/trace.go:171","msg":"trace[1843634951] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:1111; }","duration":"118.881776ms","start":"2026-02-18T05:16:48.082283Z","end":"2026-02-18T05:16:48.201165Z","steps":["trace[1843634951] 'range keys from in-memory index tree' (duration: 118.224818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:16:48.201203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.284404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-18T05:16:48.201703Z","caller":"traceutil/trace.go:171","msg":"trace[452268827] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:1111; }","duration":"116.862921ms","start":"2026-02-18T05:16:48.084825Z","end":"2026-02-18T05:16:48.201688Z","steps":["trace[452268827] 'range keys from in-memory index tree' (duration: 116.130881ms)"],"step_count":1}