{"level":"warn","ts":"2026-02-10T18:51:42.039011Z","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-10T18:51:42.039145Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.181:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.181:2380","--initial-cluster=instance=https://199.19.213.181:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.181:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.181: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-10T18:51:42.039855Z","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-10T18:51:42.039886Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-02-10T18:51:42.039917Z","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-10T18:51:42.04068Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"]} {"level":"info","ts":"2026-02-10T18:51:42.040789Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.181: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-10T18:51:42.049652Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.572545ms"} {"level":"info","ts":"2026-02-10T18:51:42.059205Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1a532052a1358225","cluster-id":"b34324311008a1d3"} {"level":"info","ts":"2026-02-10T18:51:42.05926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T18:51:42.059278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 0"} {"level":"info","ts":"2026-02-10T18:51:42.059293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1a532052a1358225 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T18:51:42.059301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 1"} {"level":"info","ts":"2026-02-10T18:51:42.059327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"warn","ts":"2026-02-10T18:51:42.064095Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T18:51:42.0792Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T18:51:42.090952Z","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-10T18:51:42.095559Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1a532052a1358225","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T18:51:42.0962Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1a532052a1358225","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T18:51:42.096308Z","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-10T18:51:42.096354Z","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-10T18:51:42.096361Z","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-10T18:51:42.096572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"info","ts":"2026-02-10T18:51:42.096645Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","added-peer-id":"1a532052a1358225","added-peer-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-02-10T18:51:42.099465Z","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-10T18:51:42.099606Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-02-10T18:51:42.099621Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-02-10T18:51:42.101639Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1a532052a1358225","initial-advertise-peer-urls":["https://199.19.213.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T18:51:42.101662Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T18:51:42.562941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T18:51:42.562985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T18:51:42.563005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgPreVoteResp from 1a532052a1358225 at term 1"} {"level":"info","ts":"2026-02-10T18:51:42.56303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became candidate at term 2"} {"level":"info","ts":"2026-02-10T18:51:42.563036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgVoteResp from 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-02-10T18:51:42.563049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became leader at term 2"} {"level":"info","ts":"2026-02-10T18:51:42.563056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1a532052a1358225 elected leader 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-02-10T18:51:42.568731Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1a532052a1358225","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.181:2379]}","request-path":"/0/members/1a532052a1358225/attributes","cluster-id":"b34324311008a1d3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T18:51:42.568919Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:51:42.569203Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:51:42.570487Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T18:51:42.571643Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.181:2379"} {"level":"info","ts":"2026-02-10T18:51:42.571725Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:51:42.575264Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T18:51:42.575357Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T18:51:42.575477Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:51:42.575622Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:51:42.575687Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:51:53.734508Z","caller":"traceutil/trace.go:171","msg":"trace[1848058678] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"135.695362ms","start":"2026-02-10T18:51:53.598791Z","end":"2026-02-10T18:51:53.734486Z","steps":["trace[1848058678] 'process raft request' (duration: 95.620936ms)","trace[1848058678] 'compare' (duration: 39.665045ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:53.941942Z","caller":"traceutil/trace.go:171","msg":"trace[1345718160] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"173.878557ms","start":"2026-02-10T18:51:53.768044Z","end":"2026-02-10T18:51:53.941922Z","steps":["trace[1345718160] 'process raft request' (duration: 167.862712ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:53.944936Z","caller":"traceutil/trace.go:171","msg":"trace[1967536443] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"174.37853ms","start":"2026-02-10T18:51:53.770548Z","end":"2026-02-10T18:51:53.944927Z","steps":["trace[1967536443] 'process raft request' (duration: 174.074892ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:53.945993Z","caller":"traceutil/trace.go:171","msg":"trace[522340924] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"175.755958ms","start":"2026-02-10T18:51:53.77023Z","end":"2026-02-10T18:51:53.945986Z","steps":["trace[522340924] 'process raft request' (duration: 171.654716ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:53.954979Z","caller":"traceutil/trace.go:171","msg":"trace[947590876] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"157.077607ms","start":"2026-02-10T18:51:53.789713Z","end":"2026-02-10T18:51:53.946791Z","steps":["trace[947590876] 'process raft request' (duration: 155.042211ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:53.955278Z","caller":"traceutil/trace.go:171","msg":"trace[931845515] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"166.735571ms","start":"2026-02-10T18:51:53.78853Z","end":"2026-02-10T18:51:53.955266Z","steps":["trace[931845515] 'process raft request' (duration: 156.192152ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:53.960093Z","caller":"traceutil/trace.go:171","msg":"trace[1606896791] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"168.829499ms","start":"2026-02-10T18:51:53.791252Z","end":"2026-02-10T18:51:53.960081Z","steps":["trace[1606896791] 'process raft request' (duration: 153.53269ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:53.944862Z","caller":"traceutil/trace.go:171","msg":"trace[1675680841] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"146.289562ms","start":"2026-02-10T18:51:53.798554Z","end":"2026-02-10T18:51:53.944843Z","steps":["trace[1675680841] 'process raft request' (duration: 146.254941ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:51:56.699869Z","caller":"traceutil/trace.go:171","msg":"trace[1202409325] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"235.632462ms","start":"2026-02-10T18:51:56.464214Z","end":"2026-02-10T18:51:56.699847Z","steps":["trace[1202409325] 'read index received' (duration: 235.462448ms)","trace[1202409325] 'applied index is now lower than readState.Index' (duration: 169.204µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:51:56.700188Z","caller":"traceutil/trace.go:171","msg":"trace[103478506] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"306.014966ms","start":"2026-02-10T18:51:56.394162Z","end":"2026-02-10T18:51:56.700177Z","steps":["trace[103478506] 'process raft request' (duration: 305.572534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:56.700676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:51:56.394154Z","time spent":"306.060667ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:51:56.700835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.843289ms","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-10T18:51:56.700955Z","caller":"traceutil/trace.go:171","msg":"trace[588898987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"144.999763ms","start":"2026-02-10T18:51:56.555933Z","end":"2026-02-10T18:51:56.700932Z","steps":["trace[588898987] 'agreement among raft nodes before linearized reading' (duration: 144.761196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:56.70103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.810974ms","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-10T18:51:56.701069Z","caller":"traceutil/trace.go:171","msg":"trace[1865741402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"236.883276ms","start":"2026-02-10T18:51:56.464174Z","end":"2026-02-10T18:51:56.701058Z","steps":["trace[1865741402] 'agreement among raft nodes before linearized reading' (duration: 236.818685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:51:56.701157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.926633ms","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-10T18:51:56.701183Z","caller":"traceutil/trace.go:171","msg":"trace[1878997039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"143.995045ms","start":"2026-02-10T18:51:56.557178Z","end":"2026-02-10T18:51:56.701173Z","steps":["trace[1878997039] 'agreement among raft nodes before linearized reading' (duration: 143.957434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:15.404726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378073635964743520,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T18:52:15.586717Z","caller":"traceutil/trace.go:171","msg":"trace[843836356] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"692.646284ms","start":"2026-02-10T18:52:14.894052Z","end":"2026-02-10T18:52:15.586698Z","steps":["trace[843836356] 'process raft request' (duration: 692.512911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:15.586837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:14.894044Z","time spent":"692.731046ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:15.628264Z","caller":"traceutil/trace.go:171","msg":"trace[530054720] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:542; }","duration":"723.829609ms","start":"2026-02-10T18:52:14.904416Z","end":"2026-02-10T18:52:15.628245Z","steps":["trace[530054720] 'read index received' (duration: 682.790824ms)","trace[530054720] 'applied index is now lower than readState.Index' (duration: 41.038135ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:15.628382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.940072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:15.628409Z","caller":"traceutil/trace.go:171","msg":"trace[1082475200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"723.996594ms","start":"2026-02-10T18:52:14.904405Z","end":"2026-02-10T18:52:15.628402Z","steps":["trace[1082475200] 'agreement among raft nodes before linearized reading' (duration: 723.910931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:15.628433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:14.9044Z","time spent":"724.027935ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:52:15.629188Z","caller":"traceutil/trace.go:171","msg":"trace[1829238335] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"735.047566ms","start":"2026-02-10T18:52:14.894132Z","end":"2026-02-10T18:52:15.62918Z","steps":["trace[1829238335] 'process raft request' (duration: 733.670238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:15.629301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:14.894128Z","time spent":"735.127898ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:15.634365Z","caller":"traceutil/trace.go:171","msg":"trace[635798826] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"738.83936ms","start":"2026-02-10T18:52:14.895488Z","end":"2026-02-10T18:52:15.634328Z","steps":["trace[635798826] 'process raft request' (duration: 732.699252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:15.634476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:14.895483Z","time spent":"738.935423ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:15.634745Z","caller":"traceutil/trace.go:171","msg":"trace[1753573306] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"381.435079ms","start":"2026-02-10T18:52:15.2533Z","end":"2026-02-10T18:52:15.634735Z","steps":["trace[1753573306] 'process raft request' (duration: 374.918811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:15.634809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:15.253284Z","time spent":"381.486541ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:17.708326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.704355ms","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-10T18:52:17.708411Z","caller":"traceutil/trace.go:171","msg":"trace[590643137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"117.809097ms","start":"2026-02-10T18:52:17.590589Z","end":"2026-02-10T18:52:17.708398Z","steps":["trace[590643137] 'range keys from in-memory index tree' (duration: 117.616783ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:18.762414Z","caller":"traceutil/trace.go:171","msg":"trace[1056721585] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"105.8433ms","start":"2026-02-10T18:52:18.656555Z","end":"2026-02-10T18:52:18.762398Z","steps":["trace[1056721585] 'process raft request' (duration: 105.513291ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:20.941494Z","caller":"traceutil/trace.go:171","msg":"trace[864180997] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"861.754868ms","start":"2026-02-10T18:52:20.079715Z","end":"2026-02-10T18:52:20.94147Z","steps":["trace[864180997] 'process raft request' (duration: 861.640995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:20.941643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:20.079698Z","time spent":"861.878022ms","remote":"127.0.0.1:48584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:21.890341Z","caller":"traceutil/trace.go:171","msg":"trace[1998161473] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"145.573228ms","start":"2026-02-10T18:52:21.744745Z","end":"2026-02-10T18:52:21.890318Z","steps":["trace[1998161473] 'read index received' (duration: 145.399773ms)","trace[1998161473] 'applied index is now lower than readState.Index' (duration: 172.355µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:21.890488Z","caller":"traceutil/trace.go:171","msg":"trace[912583110] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"154.254966ms","start":"2026-02-10T18:52:21.736224Z","end":"2026-02-10T18:52:21.890478Z","steps":["trace[912583110] 'process raft request' (duration: 153.947788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:21.890698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.937378ms","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-10T18:52:21.890729Z","caller":"traceutil/trace.go:171","msg":"trace[385965042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"146.00099ms","start":"2026-02-10T18:52:21.744717Z","end":"2026-02-10T18:52:21.890718Z","steps":["trace[385965042] 'agreement among raft nodes before linearized reading' (duration: 145.883817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:21.890852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.892276ms","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-10T18:52:21.890875Z","caller":"traceutil/trace.go:171","msg":"trace[136788316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"145.925598ms","start":"2026-02-10T18:52:21.744943Z","end":"2026-02-10T18:52:21.890868Z","steps":["trace[136788316] 'agreement among raft nodes before linearized reading' (duration: 145.854286ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:23.329719Z","caller":"traceutil/trace.go:171","msg":"trace[1395835044] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"129.522948ms","start":"2026-02-10T18:52:23.199885Z","end":"2026-02-10T18:52:23.329408Z","steps":["trace[1395835044] 'process raft request' (duration: 129.263221ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:23.339164Z","caller":"traceutil/trace.go:171","msg":"trace[1531790680] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:585; }","duration":"136.214731ms","start":"2026-02-10T18:52:23.202932Z","end":"2026-02-10T18:52:23.339147Z","steps":["trace[1531790680] 'read index received' (duration: 126.442273ms)","trace[1531790680] 'applied index is now lower than readState.Index' (duration: 9.771498ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:23.339419Z","caller":"traceutil/trace.go:171","msg":"trace[780502968] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"138.308629ms","start":"2026-02-10T18:52:23.201099Z","end":"2026-02-10T18:52:23.339407Z","steps":["trace[780502968] 'process raft request' (duration: 137.911468ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:23.339639Z","caller":"traceutil/trace.go:171","msg":"trace[1966667546] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"117.269253ms","start":"2026-02-10T18:52:23.222363Z","end":"2026-02-10T18:52:23.339632Z","steps":["trace[1966667546] 'process raft request' (duration: 116.757198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:23.339817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.86513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:23.339863Z","caller":"traceutil/trace.go:171","msg":"trace[1669665366] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:565; }","duration":"136.922491ms","start":"2026-02-10T18:52:23.202916Z","end":"2026-02-10T18:52:23.339839Z","steps":["trace[1669665366] 'agreement among raft nodes before linearized reading' (duration: 136.828319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:23.339986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.013444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:23.34003Z","caller":"traceutil/trace.go:171","msg":"trace[1678476743] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:565; }","duration":"137.056995ms","start":"2026-02-10T18:52:23.202967Z","end":"2026-02-10T18:52:23.340024Z","steps":["trace[1678476743] 'agreement among raft nodes before linearized reading' (duration: 137.000734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:23.340234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.215649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:23.340274Z","caller":"traceutil/trace.go:171","msg":"trace[108040295] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:565; }","duration":"137.26294ms","start":"2026-02-10T18:52:23.203006Z","end":"2026-02-10T18:52:23.340269Z","steps":["trace[108040295] 'agreement among raft nodes before linearized reading' (duration: 137.208579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:23.340383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.376684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:23.340457Z","caller":"traceutil/trace.go:171","msg":"trace[709506482] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:565; }","duration":"137.453906ms","start":"2026-02-10T18:52:23.202995Z","end":"2026-02-10T18:52:23.340448Z","steps":["trace[709506482] 'agreement among raft nodes before linearized reading' (duration: 137.373994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:23.340648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.633001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:23.340696Z","caller":"traceutil/trace.go:171","msg":"trace[210102745] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:565; }","duration":"137.699302ms","start":"2026-02-10T18:52:23.202984Z","end":"2026-02-10T18:52:23.340684Z","steps":["trace[210102745] 'agreement among raft nodes before linearized reading' (duration: 137.60587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:23.340778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.796735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:23.34079Z","caller":"traceutil/trace.go:171","msg":"trace[110865621] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:565; }","duration":"137.813555ms","start":"2026-02-10T18:52:23.202973Z","end":"2026-02-10T18:52:23.340786Z","steps":["trace[110865621] 'agreement among raft nodes before linearized reading' (duration: 137.790905ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:39.449674Z","caller":"traceutil/trace.go:171","msg":"trace[1474731323] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"280.303159ms","start":"2026-02-10T18:52:39.169348Z","end":"2026-02-10T18:52:39.449651Z","steps":["trace[1474731323] 'process raft request' (duration: 280.143725ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:39.450199Z","caller":"traceutil/trace.go:171","msg":"trace[1806239996] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:753; }","duration":"108.596105ms","start":"2026-02-10T18:52:39.34159Z","end":"2026-02-10T18:52:39.450186Z","steps":["trace[1806239996] 'read index received' (duration: 108.576954ms)","trace[1806239996] 'applied index is now lower than readState.Index' (duration: 18.331µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:39.450511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.903023ms","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-10T18:52:39.450547Z","caller":"traceutil/trace.go:171","msg":"trace[339050853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:725; }","duration":"108.980245ms","start":"2026-02-10T18:52:39.341553Z","end":"2026-02-10T18:52:39.450534Z","steps":["trace[339050853] 'agreement among raft nodes before linearized reading' (duration: 108.848942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:39.950436Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378073635964743919,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:52:40.067086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.976956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:52:40.067182Z","caller":"traceutil/trace.go:171","msg":"trace[818393039] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:753; }","duration":"616.813458ms","start":"2026-02-10T18:52:39.450357Z","end":"2026-02-10T18:52:40.067171Z","steps":["trace[818393039] 'read index received' (duration: 507.708399ms)","trace[818393039] 'applied index is now lower than readState.Index' (duration: 109.104369ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:40.067289Z","caller":"traceutil/trace.go:171","msg":"trace[204649962] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"707.163714ms","start":"2026-02-10T18:52:39.360119Z","end":"2026-02-10T18:52:40.067283Z","steps":["trace[204649962] 'process raft request' (duration: 597.858389ms)","trace[204649962] 'compare' (duration: 108.806111ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:40.067322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:39.360108Z","time spent":"707.198824ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:40.067409Z","caller":"traceutil/trace.go:171","msg":"trace[12936607] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"613.399594ms","start":"2026-02-10T18:52:39.454006Z","end":"2026-02-10T18:52:40.067405Z","steps":["trace[12936607] 'process raft request' (duration: 613.141167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:40.067435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:39.453994Z","time spent":"613.428826ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:40.067495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.000623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:40.067511Z","caller":"traceutil/trace.go:171","msg":"trace[587924903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"713.029974ms","start":"2026-02-10T18:52:39.354477Z","end":"2026-02-10T18:52:40.067507Z","steps":["trace[587924903] 'agreement among raft nodes before linearized reading' (duration: 713.004663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:40.067525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:39.354471Z","time spent":"713.051354ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:52:40.067595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.110786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:40.06761Z","caller":"traceutil/trace.go:171","msg":"trace[864572165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"160.151668ms","start":"2026-02-10T18:52:39.907455Z","end":"2026-02-10T18:52:40.067606Z","steps":["trace[864572165] 'agreement among raft nodes before linearized reading' (duration: 160.131057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:40.641715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378073635964743922,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:52:41.263503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.541796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:52:41.263613Z","caller":"traceutil/trace.go:171","msg":"trace[342602186] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:755; }","duration":"1.122480589s","start":"2026-02-10T18:52:40.141122Z","end":"2026-02-10T18:52:41.263602Z","steps":["trace[342602186] 'read index received' (duration: 772.795059ms)","trace[342602186] 'applied index is now lower than readState.Index' (duration: 349.68491ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:41.263739Z","caller":"traceutil/trace.go:171","msg":"trace[1519632552] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"1.190786421s","start":"2026-02-10T18:52:40.072944Z","end":"2026-02-10T18:52:41.263731Z","steps":["trace[1519632552] 'process raft request' (duration: 840.966077ms)","trace[1519632552] 'compare' (duration: 349.238568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:41.263777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:40.072932Z","time spent":"1.190823932s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:41.263886Z","caller":"traceutil/trace.go:171","msg":"trace[498801906] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"1.189317011s","start":"2026-02-10T18:52:40.074562Z","end":"2026-02-10T18:52:41.263879Z","steps":["trace[498801906] 'process raft request' (duration: 1.189008382s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:41.263916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:40.074542Z","time spent":"1.189357642s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:41.264277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123139708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T18:52:41.264303Z","caller":"traceutil/trace.go:171","msg":"trace[1333991838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:729; }","duration":"1.12322204s","start":"2026-02-10T18:52:40.141074Z","end":"2026-02-10T18:52:41.264296Z","steps":["trace[1333991838] 'agreement among raft nodes before linearized reading' (duration: 1.122908992s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:41.264323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:40.141031Z","time spent":"1.123287972s","remote":"127.0.0.1:48654","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-10T18:52:41.302763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.264463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:41.302836Z","caller":"traceutil/trace.go:171","msg":"trace[1797486447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:729; }","duration":"397.386656ms","start":"2026-02-10T18:52:40.905433Z","end":"2026-02-10T18:52:41.302819Z","steps":["trace[1797486447] 'agreement among raft nodes before linearized reading' (duration: 397.269713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:41.302876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:40.905418Z","time spent":"397.450909ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:52:41.711419Z","caller":"traceutil/trace.go:171","msg":"trace[401501025] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"198.789926ms","start":"2026-02-10T18:52:41.512607Z","end":"2026-02-10T18:52:41.711397Z","steps":["trace[401501025] 'process raft request' (duration: 198.666943ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:43.437707Z","caller":"traceutil/trace.go:171","msg":"trace[591139746] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"124.610924ms","start":"2026-02-10T18:52:43.313072Z","end":"2026-02-10T18:52:43.437683Z","steps":["trace[591139746] 'read index received' (duration: 17.681244ms)","trace[591139746] 'applied index is now lower than readState.Index' (duration: 106.928719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:43.438136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.039896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T18:52:43.438174Z","caller":"traceutil/trace.go:171","msg":"trace[387342262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:734; }","duration":"125.166869ms","start":"2026-02-10T18:52:43.312997Z","end":"2026-02-10T18:52:43.438164Z","steps":["trace[387342262] 'agreement among raft nodes before linearized reading' (duration: 124.792428ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:43.438487Z","caller":"traceutil/trace.go:171","msg":"trace[1415535957] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"166.131621ms","start":"2026-02-10T18:52:43.272345Z","end":"2026-02-10T18:52:43.438476Z","steps":["trace[1415535957] 'process raft request' (duration: 161.32978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:02.050929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378073635964744106,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T18:53:02.069849Z","caller":"traceutil/trace.go:171","msg":"trace[1130364201] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"519.295246ms","start":"2026-02-10T18:53:01.550504Z","end":"2026-02-10T18:53:02.069799Z","steps":["trace[1130364201] 'read index received' (duration: 518.902166ms)","trace[1130364201] 'applied index is now lower than readState.Index' (duration: 391.95µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:02.071873Z","caller":"traceutil/trace.go:171","msg":"trace[1648389142] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"597.507772ms","start":"2026-02-10T18:53:01.474285Z","end":"2026-02-10T18:53:02.071793Z","steps":["trace[1648389142] 'process raft request' (duration: 595.2399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:02.072295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:01.474274Z","time spent":"597.752128ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:02.073211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.711159ms","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-10T18:53:02.073287Z","caller":"traceutil/trace.go:171","msg":"trace[1062870396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"522.822502ms","start":"2026-02-10T18:53:01.550456Z","end":"2026-02-10T18:53:02.073278Z","steps":["trace[1062870396] 'agreement among raft nodes before linearized reading' (duration: 522.230386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:02.073345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:01.550433Z","time spent":"522.904444ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:53:02.073614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.898008ms","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-10T18:53:02.073832Z","caller":"traceutil/trace.go:171","msg":"trace[688111207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"414.135405ms","start":"2026-02-10T18:53:01.659673Z","end":"2026-02-10T18:53:02.073808Z","steps":["trace[688111207] 'agreement among raft nodes before linearized reading' (duration: 413.894208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:02.073949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:01.659565Z","time spent":"414.375211ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:53:02.074473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.823435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:02.074542Z","caller":"traceutil/trace.go:171","msg":"trace[1779053272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:790; }","duration":"166.931818ms","start":"2026-02-10T18:53:01.907603Z","end":"2026-02-10T18:53:02.074535Z","steps":["trace[1779053272] 'agreement among raft nodes before linearized reading' (duration: 166.624569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:02.074735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.830487ms","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-10T18:53:02.074821Z","caller":"traceutil/trace.go:171","msg":"trace[1709965258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:790; }","duration":"280.94426ms","start":"2026-02-10T18:53:01.79387Z","end":"2026-02-10T18:53:02.074814Z","steps":["trace[1709965258] 'agreement among raft nodes before linearized reading' (duration: 280.819237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:02.766495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378073635964744110,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:53:03.203928Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.113495269s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:53:03.219105Z","caller":"traceutil/trace.go:171","msg":"trace[659151832] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"1.128741655s","start":"2026-02-10T18:53:02.090329Z","end":"2026-02-10T18:53:03.219071Z","steps":["trace[659151832] 'process raft request' (duration: 1.113885459s)","trace[659151832] 'compare' (duration: 13.164239ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:03.219315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:02.0903Z","time spent":"1.128892949s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:03.263204Z","caller":"traceutil/trace.go:171","msg":"trace[1355050211] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"1.172670914s","start":"2026-02-10T18:53:02.090513Z","end":"2026-02-10T18:53:03.263184Z","steps":["trace[1355050211] 'process raft request' (duration: 1.172241412s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:03.263352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:02.090506Z","time spent":"1.172758987s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:03.26368Z","caller":"traceutil/trace.go:171","msg":"trace[974783825] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"1.173110276s","start":"2026-02-10T18:53:02.090564Z","end":"2026-02-10T18:53:03.263675Z","steps":["trace[974783825] 'process raft request' (duration: 1.172581552s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:03.26372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:02.090559Z","time spent":"1.173140907s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:03.263595Z","caller":"traceutil/trace.go:171","msg":"trace[170399956] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:823; }","duration":"997.129092ms","start":"2026-02-10T18:53:02.266453Z","end":"2026-02-10T18:53:03.263582Z","steps":["trace[170399956] 'read index received' (duration: 937.782142ms)","trace[170399956] 'applied index is now lower than readState.Index' (duration: 59.34576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:03.263906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.45766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-10T18:53:03.263933Z","caller":"traceutil/trace.go:171","msg":"trace[1481095366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"997.520002ms","start":"2026-02-10T18:53:02.266403Z","end":"2026-02-10T18:53:03.263923Z","steps":["trace[1481095366] 'agreement among raft nodes before linearized reading' (duration: 997.418079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:03.263954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:02.266371Z","time spent":"997.578104ms","remote":"127.0.0.1:48654","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":"info","ts":"2026-02-10T18:53:03.375285Z","caller":"traceutil/trace.go:171","msg":"trace[324900338] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"106.812066ms","start":"2026-02-10T18:53:03.268451Z","end":"2026-02-10T18:53:03.375263Z","steps":["trace[324900338] 'process raft request' (duration: 54.064505ms)","trace[324900338] 'compare' (duration: 52.602297ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:07.843076Z","caller":"traceutil/trace.go:171","msg":"trace[1808119714] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"452.799432ms","start":"2026-02-10T18:53:07.390255Z","end":"2026-02-10T18:53:07.843055Z","steps":["trace[1808119714] 'read index received' (duration: 452.589536ms)","trace[1808119714] 'applied index is now lower than readState.Index' (duration: 209.116µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:07.843503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.230173ms","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-10T18:53:07.843564Z","caller":"traceutil/trace.go:171","msg":"trace[1428190934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"453.393477ms","start":"2026-02-10T18:53:07.390159Z","end":"2026-02-10T18:53:07.843553Z","steps":["trace[1428190934] 'agreement among raft nodes before linearized reading' (duration: 452.983516ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:07.843763Z","caller":"traceutil/trace.go:171","msg":"trace[13685621] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"535.614292ms","start":"2026-02-10T18:53:07.308037Z","end":"2026-02-10T18:53:07.843651Z","steps":["trace[13685621] 'process raft request' (duration: 534.884432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:07.844114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:07.307993Z","time spent":"535.89903ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:07.844754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:07.390134Z","time spent":"453.566522ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:53:08.313673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.993078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:53:08.31373Z","caller":"traceutil/trace.go:171","msg":"trace[604921500] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"207.68499ms","start":"2026-02-10T18:53:08.106036Z","end":"2026-02-10T18:53:08.313721Z","steps":["trace[604921500] 'read index received' (duration: 35.711µs)","trace[604921500] 'applied index is now lower than readState.Index' (duration: 207.648669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:08.313814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.779022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T18:53:08.313827Z","caller":"traceutil/trace.go:171","msg":"trace[101901521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"207.824623ms","start":"2026-02-10T18:53:08.105997Z","end":"2026-02-10T18:53:08.313822Z","steps":["trace[101901521] 'agreement among raft nodes before linearized reading' (duration: 207.746941ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:08.31395Z","caller":"traceutil/trace.go:171","msg":"trace[1041876512] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"447.586599ms","start":"2026-02-10T18:53:07.866354Z","end":"2026-02-10T18:53:08.313941Z","steps":["trace[1041876512] 'process raft request' (duration: 113.277573ms)","trace[1041876512] 'compare' (duration: 333.823442ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:08.313976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:07.866332Z","time spent":"447.629649ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:09.104364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.476226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:53:09.104446Z","caller":"traceutil/trace.go:171","msg":"trace[18478926] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"511.957055ms","start":"2026-02-10T18:53:08.592474Z","end":"2026-02-10T18:53:09.104431Z","steps":["trace[18478926] 'read index received' (duration: 38.601µs)","trace[18478926] 'applied index is now lower than readState.Index' (duration: 511.916974ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:09.104643Z","caller":"traceutil/trace.go:171","msg":"trace[1493054834] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"787.992762ms","start":"2026-02-10T18:53:08.316636Z","end":"2026-02-10T18:53:09.104629Z","steps":["trace[1493054834] 'process raft request' (duration: 170.174026ms)","trace[1493054834] 'compare' (duration: 617.24425ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:09.104702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:08.316624Z","time spent":"788.045333ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:09.104875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.394918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:09.104908Z","caller":"traceutil/trace.go:171","msg":"trace[709137356] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:807; }","duration":"512.461719ms","start":"2026-02-10T18:53:08.592435Z","end":"2026-02-10T18:53:09.104896Z","steps":["trace[709137356] 'agreement among raft nodes before linearized reading' (duration: 512.410268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:09.104937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:08.592416Z","time spent":"512.513431ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:53:09.105069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.834382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:09.105094Z","caller":"traceutil/trace.go:171","msg":"trace[1304312559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"197.888583ms","start":"2026-02-10T18:53:08.907198Z","end":"2026-02-10T18:53:09.105086Z","steps":["trace[1304312559] 'agreement among raft nodes before linearized reading' (duration: 197.851132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:09.783771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.264791ms","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-10T18:53:09.78453Z","caller":"traceutil/trace.go:171","msg":"trace[1163900595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:807; }","duration":"433.067233ms","start":"2026-02-10T18:53:09.351448Z","end":"2026-02-10T18:53:09.784516Z","steps":["trace[1163900595] 'range keys from in-memory index tree' (duration: 432.124417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:09.784689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:09.351434Z","time spent":"433.239838ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:53:09.784138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.407373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:09.784908Z","caller":"traceutil/trace.go:171","msg":"trace[1990652858] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:807; }","duration":"578.213665ms","start":"2026-02-10T18:53:09.206684Z","end":"2026-02-10T18:53:09.784897Z","steps":["trace[1990652858] 'count revisions from in-memory index tree' (duration: 577.341061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:09.784941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:09.206653Z","time spent":"578.277237ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:53:09.784198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.078573ms","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-10T18:53:09.785107Z","caller":"traceutil/trace.go:171","msg":"trace[1217182874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:807; }","duration":"534.024829ms","start":"2026-02-10T18:53:09.251075Z","end":"2026-02-10T18:53:09.785099Z","steps":["trace[1217182874] 'range keys from in-memory index tree' (duration: 532.97422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:09.785131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:09.251053Z","time spent":"534.07144ms","remote":"127.0.0.1:48654","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-10T18:53:10.252265Z","caller":"traceutil/trace.go:171","msg":"trace[1283394437] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"463.749581ms","start":"2026-02-10T18:53:09.788486Z","end":"2026-02-10T18:53:10.252235Z","steps":["trace[1283394437] 'process raft request' (duration: 463.559475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:10.25247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:09.788472Z","time spent":"463.891164ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:10.929157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.503562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:53:10.930589Z","caller":"traceutil/trace.go:171","msg":"trace[1106687497] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"400.889824ms","start":"2026-02-10T18:53:10.528406Z","end":"2026-02-10T18:53:10.929295Z","steps":["trace[1106687497] 'process raft request' (duration: 400.836393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:10.930677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:10.528304Z","time spent":"402.331124ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:10.930791Z","caller":"traceutil/trace.go:171","msg":"trace[1084065315] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"1.14173188s","start":"2026-02-10T18:53:09.789048Z","end":"2026-02-10T18:53:10.93078Z","steps":["trace[1084065315] 'process raft request' (duration: 605.539692ms)","trace[1084065315] 'compare' (duration: 534.010788ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:10.930839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:09.789039Z","time spent":"1.141777391s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:10.93287Z","caller":"traceutil/trace.go:171","msg":"trace[968545505] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"1.083547301s","start":"2026-02-10T18:53:09.847654Z","end":"2026-02-10T18:53:10.931201Z","steps":["trace[968545505] 'read index received' (duration: 405.181382ms)","trace[968545505] 'applied index is now lower than readState.Index' (duration: 678.364739ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:10.933054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085389892s","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-10T18:53:10.933085Z","caller":"traceutil/trace.go:171","msg":"trace[1020121563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:810; }","duration":"1.085449183s","start":"2026-02-10T18:53:09.847625Z","end":"2026-02-10T18:53:10.933074Z","steps":["trace[1020121563] 'agreement among raft nodes before linearized reading' (duration: 1.085331849s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:10.933223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02564617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:10.933285Z","caller":"traceutil/trace.go:171","msg":"trace[894111613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"1.025788714s","start":"2026-02-10T18:53:09.907486Z","end":"2026-02-10T18:53:10.933275Z","steps":["trace[894111613] 'agreement among raft nodes before linearized reading' (duration: 1.025711422s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:10.933318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:09.907433Z","time spent":"1.025873606s","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:53:10.93361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.325001ms","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-10T18:53:10.933647Z","caller":"traceutil/trace.go:171","msg":"trace[832624376] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"614.394753ms","start":"2026-02-10T18:53:10.319243Z","end":"2026-02-10T18:53:10.933638Z","steps":["trace[832624376] 'agreement among raft nodes before linearized reading' (duration: 614.309841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:10.933675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:10.319226Z","time spent":"614.440025ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:53:10.933771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:09.84761Z","time spent":"1.085497955s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:53:10.934242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.485585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:53:10.934279Z","caller":"traceutil/trace.go:171","msg":"trace[339334093] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:810; }","duration":"844.585307ms","start":"2026-02-10T18:53:10.089685Z","end":"2026-02-10T18:53:10.93427Z","steps":["trace[339334093] 'agreement among raft nodes before linearized reading' (duration: 844.516645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:10.934311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:10.089664Z","time spent":"844.636909ms","remote":"127.0.0.1:48854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:53:11.449707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378073635964744166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:53:11.6844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.610317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:53:11.684509Z","caller":"traceutil/trace.go:171","msg":"trace[51563510] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"746.52523ms","start":"2026-02-10T18:53:10.937964Z","end":"2026-02-10T18:53:11.684489Z","steps":["trace[51563510] 'process raft request' (duration: 564.702747ms)","trace[51563510] 'compare' (duration: 181.370741ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:11.684558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:10.937953Z","time spent":"746.582582ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:11.741115Z","caller":"traceutil/trace.go:171","msg":"trace[1102496402] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"801.051609ms","start":"2026-02-10T18:53:10.940021Z","end":"2026-02-10T18:53:11.741073Z","steps":["trace[1102496402] 'process raft request' (duration: 799.871277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:11.741296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:10.940015Z","time spent":"801.187313ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:11.866276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.194085ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T18:53:11.866369Z","caller":"traceutil/trace.go:171","msg":"trace[1168577075] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:844; }","duration":"925.320901ms","start":"2026-02-10T18:53:10.94103Z","end":"2026-02-10T18:53:11.866351Z","steps":["trace[1168577075] 'read index received' (duration: 561.590302ms)","trace[1168577075] 'applied index is now lower than readState.Index' (duration: 363.729199ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:11.866527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.481296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:11.866557Z","caller":"traceutil/trace.go:171","msg":"trace[1720111713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"925.530238ms","start":"2026-02-10T18:53:10.941017Z","end":"2026-02-10T18:53:11.866547Z","steps":["trace[1720111713] 'agreement among raft nodes before linearized reading' (duration: 925.469746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:11.866591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:10.941011Z","time spent":"925.573079ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:53:11.867015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.321918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T18:53:11.867056Z","caller":"traceutil/trace.go:171","msg":"trace[1143967793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:812; }","duration":"756.39779ms","start":"2026-02-10T18:53:11.110645Z","end":"2026-02-10T18:53:11.867043Z","steps":["trace[1143967793] 'agreement among raft nodes before linearized reading' (duration: 756.208585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:11.868115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:11.110627Z","time spent":"757.471219ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-10T18:53:12.031273Z","caller":"traceutil/trace.go:171","msg":"trace[448056263] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"159.177905ms","start":"2026-02-10T18:53:11.872077Z","end":"2026-02-10T18:53:12.031255Z","steps":["trace[448056263] 'process raft request' (duration: 159.068942ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:23.118552Z","caller":"traceutil/trace.go:171","msg":"trace[1719266220] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"138.145811ms","start":"2026-02-10T18:53:22.980388Z","end":"2026-02-10T18:53:23.118534Z","steps":["trace[1719266220] 'process raft request' (duration: 138.036688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:24.357484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378073635964744356,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:53:24.858221Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378073635964744356,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:53:25.25137Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.52378798s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:53:25.251719Z","caller":"traceutil/trace.go:171","msg":"trace[1440160223] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"1.395092597s","start":"2026-02-10T18:53:23.856603Z","end":"2026-02-10T18:53:25.251695Z","steps":["trace[1440160223] 'read index received' (duration: 1.394908732s)","trace[1440160223] 'applied index is now lower than readState.Index' (duration: 183.045µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:25.252041Z","caller":"traceutil/trace.go:171","msg":"trace[1496266085] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"1.52451577s","start":"2026-02-10T18:53:23.727514Z","end":"2026-02-10T18:53:25.25203Z","steps":["trace[1496266085] 'process raft request' (duration: 1.524057958s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:25.252141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:23.727454Z","time spent":"1.524613972s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:25.252372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395765385s","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-10T18:53:25.252424Z","caller":"traceutil/trace.go:171","msg":"trace[512452527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"1.395818887s","start":"2026-02-10T18:53:23.856574Z","end":"2026-02-10T18:53:25.252392Z","steps":["trace[512452527] 'agreement among raft nodes before linearized reading' (duration: 1.395718214s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:25.252452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:23.85656Z","time spent":"1.395885459s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:53:25.25267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346549052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:25.252699Z","caller":"traceutil/trace.go:171","msg":"trace[1031421759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"1.346601492s","start":"2026-02-10T18:53:23.906089Z","end":"2026-02-10T18:53:25.252691Z","steps":["trace[1031421759] 'agreement among raft nodes before linearized reading' (duration: 1.346559171s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:25.252723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:23.906073Z","time spent":"1.346644594s","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:53:25.253722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273642461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:53:25.253749Z","caller":"traceutil/trace.go:171","msg":"trace[526970979] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:896; }","duration":"1.273697973s","start":"2026-02-10T18:53:23.980045Z","end":"2026-02-10T18:53:25.253742Z","steps":["trace[526970979] 'agreement among raft nodes before linearized reading' (duration: 1.273642242s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:25.25377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:23.980031Z","time spent":"1.273731844s","remote":"127.0.0.1:48720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:53:25.254103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.482401ms","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-10T18:53:25.25413Z","caller":"traceutil/trace.go:171","msg":"trace[1410140956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:896; }","duration":"864.544692ms","start":"2026-02-10T18:53:24.389577Z","end":"2026-02-10T18:53:25.254122Z","steps":["trace[1410140956] 'agreement among raft nodes before linearized reading' (duration: 864.392708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:25.254148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:24.389563Z","time spent":"864.581113ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T18:53:25.254334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072428398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:53:25.254357Z","caller":"traceutil/trace.go:171","msg":"trace[1646497637] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:896; }","duration":"1.07248749s","start":"2026-02-10T18:53:24.181864Z","end":"2026-02-10T18:53:25.254352Z","steps":["trace[1646497637] 'agreement among raft nodes before linearized reading' (duration: 1.072444979s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:25.254503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:24.181847Z","time spent":"1.072644623s","remote":"127.0.0.1:48806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:53:25.254754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180163559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T18:53:25.254812Z","caller":"traceutil/trace.go:171","msg":"trace[994900782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"1.180222881s","start":"2026-02-10T18:53:24.074582Z","end":"2026-02-10T18:53:25.254805Z","steps":["trace[994900782] 'agreement among raft nodes before linearized reading' (duration: 1.180052746s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:25.254866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:24.074548Z","time spent":"1.180311524s","remote":"127.0.0.1:48654","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-10T18:53:25.255805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.552186ms","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-10T18:53:25.255886Z","caller":"traceutil/trace.go:171","msg":"trace[59872369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"133.666939ms","start":"2026-02-10T18:53:25.122212Z","end":"2026-02-10T18:53:25.255879Z","steps":["trace[59872369] 'agreement among raft nodes before linearized reading' (duration: 130.679448ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:35.635106Z","caller":"traceutil/trace.go:171","msg":"trace[513158048] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"250.129929ms","start":"2026-02-10T18:53:35.384951Z","end":"2026-02-10T18:53:35.635081Z","steps":["trace[513158048] 'read index received' (duration: 249.953654ms)","trace[513158048] 'applied index is now lower than readState.Index' (duration: 175.315µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:35.635235Z","caller":"traceutil/trace.go:171","msg":"trace[377404064] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"265.296833ms","start":"2026-02-10T18:53:35.369928Z","end":"2026-02-10T18:53:35.635225Z","steps":["trace[377404064] 'process raft request' (duration: 265.004695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:35.635532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.558131ms","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-10T18:53:35.635579Z","caller":"traceutil/trace.go:171","msg":"trace[1228272969] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"250.803257ms","start":"2026-02-10T18:53:35.384763Z","end":"2026-02-10T18:53:35.635566Z","steps":["trace[1228272969] 'agreement among raft nodes before linearized reading' (duration: 250.577871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:35.635685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.286324ms","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-10T18:53:35.635754Z","caller":"traceutil/trace.go:171","msg":"trace[430347200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:924; }","duration":"246.372596ms","start":"2026-02-10T18:53:35.389364Z","end":"2026-02-10T18:53:35.635736Z","steps":["trace[430347200] 'agreement among raft nodes before linearized reading' (duration: 246.198291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:35.635884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.440247ms","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-10T18:53:35.63591Z","caller":"traceutil/trace.go:171","msg":"trace[1885665094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"239.474798ms","start":"2026-02-10T18:53:35.396428Z","end":"2026-02-10T18:53:35.635903Z","steps":["trace[1885665094] 'agreement among raft nodes before linearized reading' (duration: 239.412886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:35.635952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.124106ms","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-10T18:53:35.635981Z","caller":"traceutil/trace.go:171","msg":"trace[1368814537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:924; }","duration":"240.163147ms","start":"2026-02-10T18:53:35.395809Z","end":"2026-02-10T18:53:35.635972Z","steps":["trace[1368814537] 'agreement among raft nodes before linearized reading' (duration: 240.086484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:36.297665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.294163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:53:36.297759Z","caller":"traceutil/trace.go:171","msg":"trace[1719354975] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"654.647872ms","start":"2026-02-10T18:53:35.643094Z","end":"2026-02-10T18:53:36.297742Z","steps":["trace[1719354975] 'process raft request' (duration: 289.208535ms)","trace[1719354975] 'compare' (duration: 364.82857ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:36.297813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:35.643078Z","time spent":"654.710084ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:36.953831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.596217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:53:36.954597Z","caller":"traceutil/trace.go:171","msg":"trace[1267502532] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:966; }","duration":"1.014169457s","start":"2026-02-10T18:53:35.940412Z","end":"2026-02-10T18:53:36.954582Z","steps":["trace[1267502532] 'read index received' (duration: 479.745517ms)","trace[1267502532] 'applied index is now lower than readState.Index' (duration: 534.42316ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:36.954805Z","caller":"traceutil/trace.go:171","msg":"trace[432816702] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"1.311556965s","start":"2026-02-10T18:53:35.643237Z","end":"2026-02-10T18:53:36.954793Z","steps":["trace[432816702] 'process raft request' (duration: 776.92786ms)","trace[432816702] 'compare' (duration: 533.116034ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:36.954864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:35.64323Z","time spent":"1.311601166s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:36.955133Z","caller":"traceutil/trace.go:171","msg":"trace[1545177069] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"1.311771891s","start":"2026-02-10T18:53:35.643349Z","end":"2026-02-10T18:53:36.955121Z","steps":["trace[1545177069] 'process raft request' (duration: 1.310561128s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:36.955198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:35.643343Z","time spent":"1.311825643s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:36.955711Z","caller":"traceutil/trace.go:171","msg":"trace[31981589] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"1.312226424s","start":"2026-02-10T18:53:35.643458Z","end":"2026-02-10T18:53:36.955684Z","steps":["trace[31981589] 'process raft request' (duration: 1.311089423s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:36.955813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:35.643452Z","time spent":"1.312308176s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:36.955961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015550795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.181\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T18:53:36.955977Z","caller":"traceutil/trace.go:171","msg":"trace[597667970] range","detail":"{range_begin:/registry/masterleases/199.19.213.181; range_end:; response_count:1; response_revision:928; }","duration":"1.015604376s","start":"2026-02-10T18:53:35.940367Z","end":"2026-02-10T18:53:36.955972Z","steps":["trace[597667970] 'agreement among raft nodes before linearized reading' (duration: 1.015523634s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:36.955991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:35.940348Z","time spent":"1.015640027s","remote":"127.0.0.1:48442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.181\" "} {"level":"warn","ts":"2026-02-10T18:53:36.956119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.688673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:36.956135Z","caller":"traceutil/trace.go:171","msg":"trace[1337238453] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:928; }","duration":"413.740385ms","start":"2026-02-10T18:53:36.542389Z","end":"2026-02-10T18:53:36.956129Z","steps":["trace[1337238453] 'agreement among raft nodes before linearized reading' (duration: 413.715144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:36.956148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:36.542371Z","time spent":"413.772766ms","remote":"127.0.0.1:57374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-02-10T18:53:37.093506Z","caller":"traceutil/trace.go:171","msg":"trace[1051338210] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"135.643613ms","start":"2026-02-10T18:53:36.957828Z","end":"2026-02-10T18:53:37.093472Z","steps":["trace[1051338210] 'read index received' (duration: 104.3894ms)","trace[1051338210] 'applied index is now lower than readState.Index' (duration: 31.252823ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:37.093853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.008623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:37.093937Z","caller":"traceutil/trace.go:171","msg":"trace[1313570750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"136.136797ms","start":"2026-02-10T18:53:36.957789Z","end":"2026-02-10T18:53:37.093926Z","steps":["trace[1313570750] 'agreement among raft nodes before linearized reading' (duration: 136.018564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:37.094101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.634915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:37.094136Z","caller":"traceutil/trace.go:171","msg":"trace[533829807] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:928; }","duration":"119.695237ms","start":"2026-02-10T18:53:36.974433Z","end":"2026-02-10T18:53:37.094128Z","steps":["trace[533829807] 'agreement among raft nodes before linearized reading' (duration: 119.641936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:37.37333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.836453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:53:37.373491Z","caller":"traceutil/trace.go:171","msg":"trace[27231000] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"206.151268ms","start":"2026-02-10T18:53:37.167257Z","end":"2026-02-10T18:53:37.373409Z","steps":["trace[27231000] 'process raft request' (duration: 94.171301ms)","trace[27231000] 'compare' (duration: 111.753421ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:38.473131Z","caller":"traceutil/trace.go:171","msg":"trace[1597490653] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"160.108101ms","start":"2026-02-10T18:53:38.313005Z","end":"2026-02-10T18:53:38.473114Z","steps":["trace[1597490653] 'process raft request' (duration: 160.002928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:40.128978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224493028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:40.12903Z","caller":"traceutil/trace.go:171","msg":"trace[1301792348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"1.22455963s","start":"2026-02-10T18:53:38.904458Z","end":"2026-02-10T18:53:40.129018Z","steps":["trace[1301792348] 'range keys from in-memory index tree' (duration: 1.224441737s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:40.129053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:38.904422Z","time spent":"1.224626902s","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:53:40.129011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.648159ms","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-10T18:53:40.129107Z","caller":"traceutil/trace.go:171","msg":"trace[1206087241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"407.773423ms","start":"2026-02-10T18:53:39.721316Z","end":"2026-02-10T18:53:40.129089Z","steps":["trace[1206087241] 'range keys from in-memory index tree' (duration: 407.520555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:40.129164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:39.721303Z","time spent":"407.851254ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T18:53:40.12926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16780783s","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-10T18:53:40.129277Z","caller":"traceutil/trace.go:171","msg":"trace[1949337677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:932; }","duration":"1.167832531s","start":"2026-02-10T18:53:38.96144Z","end":"2026-02-10T18:53:40.129273Z","steps":["trace[1949337677] 'range keys from in-memory index tree' (duration: 1.167734439s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:40.129295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:38.961436Z","time spent":"1.167852042s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:53:40.12935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167940014s","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-10T18:53:40.12936Z","caller":"traceutil/trace.go:171","msg":"trace[927638564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"1.167949944s","start":"2026-02-10T18:53:38.961407Z","end":"2026-02-10T18:53:40.129357Z","steps":["trace[927638564] 'range keys from in-memory index tree' (duration: 1.167833591s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:40.129377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:38.961371Z","time spent":"1.168002985s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:53:40.129473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167985105s","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-10T18:53:40.129485Z","caller":"traceutil/trace.go:171","msg":"trace[877802147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:932; }","duration":"1.168002485s","start":"2026-02-10T18:53:38.961479Z","end":"2026-02-10T18:53:40.129481Z","steps":["trace[877802147] 'range keys from in-memory index tree' (duration: 1.167947044s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:40.129496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:38.961477Z","time spent":"1.168016776s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:53:40.129703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01503234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:53:40.129718Z","caller":"traceutil/trace.go:171","msg":"trace[1500066153] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:932; }","duration":"1.015074201s","start":"2026-02-10T18:53:39.114639Z","end":"2026-02-10T18:53:40.129714Z","steps":["trace[1500066153] 'count revisions from in-memory index tree' (duration: 1.014982118s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:40.129731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:39.114626Z","time spent":"1.015101292s","remote":"127.0.0.1:48792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:53:40.640484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378073635964744465,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T18:53:41.078467Z","caller":"traceutil/trace.go:171","msg":"trace[1123192200] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"942.574633ms","start":"2026-02-10T18:53:40.135877Z","end":"2026-02-10T18:53:41.078452Z","steps":["trace[1123192200] 'process raft request' (duration: 942.46193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:41.078557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:40.13587Z","time spent":"942.640294ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:41.253743Z","caller":"traceutil/trace.go:171","msg":"trace[79606049] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.117511937s","start":"2026-02-10T18:53:40.136213Z","end":"2026-02-10T18:53:41.253724Z","steps":["trace[79606049] 'process raft request' (duration: 1.031254143s)","trace[79606049] 'compare' (duration: 85.995597ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:41.25383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113727324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-10T18:53:41.253845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:40.136207Z","time spent":"1.117587559s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:41.253752Z","caller":"traceutil/trace.go:171","msg":"trace[234655174] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:975; }","duration":"1.113648242s","start":"2026-02-10T18:53:40.140089Z","end":"2026-02-10T18:53:41.253737Z","steps":["trace[234655174] 'read index received' (duration: 938.732108ms)","trace[234655174] 'applied index is now lower than readState.Index' (duration: 174.915564ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:41.253852Z","caller":"traceutil/trace.go:171","msg":"trace[580245397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"1.113782636s","start":"2026-02-10T18:53:40.140064Z","end":"2026-02-10T18:53:41.253847Z","steps":["trace[580245397] 'agreement among raft nodes before linearized reading' (duration: 1.113717734s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:41.253952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:40.14006Z","time spent":"1.113885748s","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:53:41.25396Z","caller":"traceutil/trace.go:171","msg":"trace[2115074091] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"1.117710233s","start":"2026-02-10T18:53:40.136244Z","end":"2026-02-10T18:53:41.253954Z","steps":["trace[2115074091] 'process raft request' (duration: 1.117411464s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:41.25401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:40.13624Z","time spent":"1.117736813s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:41.254077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.729431ms","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-10T18:53:41.254096Z","caller":"traceutil/trace.go:171","msg":"trace[1199478197] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"1.117822895s","start":"2026-02-10T18:53:40.136268Z","end":"2026-02-10T18:53:41.254091Z","steps":["trace[1199478197] 'process raft request' (duration: 1.117441915s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:41.254122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:40.136265Z","time spent":"1.117844186s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:41.254161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.331756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:53:41.254177Z","caller":"traceutil/trace.go:171","msg":"trace[1229627637] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:936; }","duration":"412.373126ms","start":"2026-02-10T18:53:40.841798Z","end":"2026-02-10T18:53:41.254171Z","steps":["trace[1229627637] 'agreement among raft nodes before linearized reading' (duration: 412.345176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:41.25419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:40.841784Z","time spent":"412.402288ms","remote":"127.0.0.1:48840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-10T18:53:41.254096Z","caller":"traceutil/trace.go:171","msg":"trace[9142549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:936; }","duration":"777.768782ms","start":"2026-02-10T18:53:40.476321Z","end":"2026-02-10T18:53:41.25409Z","steps":["trace[9142549] 'agreement among raft nodes before linearized reading' (duration: 777.70348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:41.254232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:40.47631Z","time spent":"777.915116ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-10T18:53:43.81371Z","caller":"traceutil/trace.go:171","msg":"trace[423772321] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"551.881467ms","start":"2026-02-10T18:53:43.261811Z","end":"2026-02-10T18:53:43.813692Z","steps":["trace[423772321] 'process raft request' (duration: 551.705482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:43.81402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:43.261793Z","time spent":"551.977719ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:44.340514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.895332ms","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-10T18:53:44.340625Z","caller":"traceutil/trace.go:171","msg":"trace[1955094532] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:942; }","duration":"291.015905ms","start":"2026-02-10T18:53:44.049593Z","end":"2026-02-10T18:53:44.340609Z","steps":["trace[1955094532] 'range keys from in-memory index tree' (duration: 290.880631ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:44.341977Z","caller":"traceutil/trace.go:171","msg":"trace[1527613537] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:984; }","duration":"1.012246335s","start":"2026-02-10T18:53:43.329714Z","end":"2026-02-10T18:53:44.34196Z","steps":["trace[1527613537] 'read index received' (duration: 483.665674ms)","trace[1527613537] 'applied index is now lower than readState.Index' (duration: 528.580081ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:44.34227Z","caller":"traceutil/trace.go:171","msg":"trace[500013229] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"1.078857763s","start":"2026-02-10T18:53:43.263406Z","end":"2026-02-10T18:53:44.342263Z","steps":["trace[500013229] 'process raft request' (duration: 1.078350019s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:44.342647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:43.263395Z","time spent":"1.078908385s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:44.342782Z","caller":"traceutil/trace.go:171","msg":"trace[1884349398] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"1.077551948s","start":"2026-02-10T18:53:43.265225Z","end":"2026-02-10T18:53:44.342777Z","steps":["trace[1884349398] 'process raft request' (duration: 1.076709215s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:44.343006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:43.265212Z","time spent":"1.077768524s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:44.343155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013440097s","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-10T18:53:44.343197Z","caller":"traceutil/trace.go:171","msg":"trace[880269991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:944; }","duration":"1.013504608s","start":"2026-02-10T18:53:43.329686Z","end":"2026-02-10T18:53:44.343191Z","steps":["trace[880269991] 'agreement among raft nodes before linearized reading' (duration: 1.013407946s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:44.343224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:43.329673Z","time spent":"1.01354684s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:53:44.343339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.371771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4740"} {"level":"info","ts":"2026-02-10T18:53:44.343375Z","caller":"traceutil/trace.go:171","msg":"trace[1751799959] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:944; }","duration":"522.406842ms","start":"2026-02-10T18:53:43.820963Z","end":"2026-02-10T18:53:44.34337Z","steps":["trace[1751799959] 'agreement among raft nodes before linearized reading' (duration: 522.34366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:44.343418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:43.820952Z","time spent":"522.461293ms","remote":"127.0.0.1:48570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4763,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-10T18:53:44.578634Z","caller":"traceutil/trace.go:171","msg":"trace[1300415879] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"230.587185ms","start":"2026-02-10T18:53:44.348018Z","end":"2026-02-10T18:53:44.578605Z","steps":["trace[1300415879] 'process raft request' (duration: 193.135543ms)","trace[1300415879] 'compare' (duration: 37.340259ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:44.656346Z","caller":"traceutil/trace.go:171","msg":"trace[168001231] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:987; }","duration":"159.450253ms","start":"2026-02-10T18:53:44.496876Z","end":"2026-02-10T18:53:44.656326Z","steps":["trace[168001231] 'read index received' (duration: 44.365291ms)","trace[168001231] 'applied index is now lower than readState.Index' (duration: 115.084162ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:44.656459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.562246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:44.65656Z","caller":"traceutil/trace.go:171","msg":"trace[1113052345] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:946; }","duration":"159.70775ms","start":"2026-02-10T18:53:44.496842Z","end":"2026-02-10T18:53:44.65655Z","steps":["trace[1113052345] 'agreement among raft nodes before linearized reading' (duration: 159.551156ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:44.656741Z","caller":"traceutil/trace.go:171","msg":"trace[1386987728] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"222.957186ms","start":"2026-02-10T18:53:44.433774Z","end":"2026-02-10T18:53:44.656731Z","steps":["trace[1386987728] 'process raft request' (duration: 222.473893ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:44.952968Z","caller":"traceutil/trace.go:171","msg":"trace[322175933] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"292.473114ms","start":"2026-02-10T18:53:44.660473Z","end":"2026-02-10T18:53:44.952946Z","steps":["trace[322175933] 'process raft request' (duration: 208.07625ms)","trace[322175933] 'compare' (duration: 83.953052ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:45.558321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.124424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T18:53:45.558583Z","caller":"traceutil/trace.go:171","msg":"trace[514365590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:947; }","duration":"470.427833ms","start":"2026-02-10T18:53:45.088137Z","end":"2026-02-10T18:53:45.558565Z","steps":["trace[514365590] 'range keys from in-memory index tree' (duration: 469.935599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:45.558769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:45.088122Z","time spent":"470.631319ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-10T18:53:46.2672Z","caller":"traceutil/trace.go:171","msg":"trace[1554073426] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"361.467738ms","start":"2026-02-10T18:53:45.905714Z","end":"2026-02-10T18:53:46.267182Z","steps":["trace[1554073426] 'read index received' (duration: 361.318794ms)","trace[1554073426] 'applied index is now lower than readState.Index' (duration: 148.124µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:46.268786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.606408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:53:46.268857Z","caller":"traceutil/trace.go:171","msg":"trace[545892125] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:949; }","duration":"333.70865ms","start":"2026-02-10T18:53:45.935131Z","end":"2026-02-10T18:53:46.268839Z","steps":["trace[545892125] 'agreement among raft nodes before linearized reading' (duration: 333.577617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:46.268919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:45.935123Z","time spent":"333.778403ms","remote":"127.0.0.1:48778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:53:46.268975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.243837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:46.269007Z","caller":"traceutil/trace.go:171","msg":"trace[2013287897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"363.308548ms","start":"2026-02-10T18:53:45.905689Z","end":"2026-02-10T18:53:46.268998Z","steps":["trace[2013287897] 'agreement among raft nodes before linearized reading' (duration: 363.245847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:46.269052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:45.905681Z","time spent":"363.36508ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:53:46.269211Z","caller":"traceutil/trace.go:171","msg":"trace[56910274] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"378.804952ms","start":"2026-02-10T18:53:45.890398Z","end":"2026-02-10T18:53:46.269203Z","steps":["trace[56910274] 'process raft request' (duration: 376.644683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:46.269265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:45.890373Z","time spent":"378.855673ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:46.269296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.773646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.181\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T18:53:46.269341Z","caller":"traceutil/trace.go:171","msg":"trace[1498180301] range","detail":"{range_begin:/registry/masterleases/199.19.213.181; range_end:; response_count:1; response_revision:949; }","duration":"328.837687ms","start":"2026-02-10T18:53:45.940489Z","end":"2026-02-10T18:53:46.269327Z","steps":["trace[1498180301] 'agreement among raft nodes before linearized reading' (duration: 328.707554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:46.269378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:45.940483Z","time spent":"328.887509ms","remote":"127.0.0.1:48442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.181\" "} {"level":"warn","ts":"2026-02-10T18:53:46.770539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378073635964744506,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:53:47.109054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.887409ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-10T18:53:47.109136Z","caller":"traceutil/trace.go:171","msg":"trace[267026748] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"838.601864ms","start":"2026-02-10T18:53:46.270519Z","end":"2026-02-10T18:53:47.109121Z","steps":["trace[267026748] 'read index received' (duration: 628.589311ms)","trace[267026748] 'applied index is now lower than readState.Index' (duration: 210.011253ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:47.109212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:46.270391Z","time spent":"838.819129ms","remote":"127.0.0.1:48442","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-10T18:53:47.109435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.907872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:47.109467Z","caller":"traceutil/trace.go:171","msg":"trace[1590659949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"838.942103ms","start":"2026-02-10T18:53:46.270515Z","end":"2026-02-10T18:53:47.109457Z","steps":["trace[1590659949] 'agreement among raft nodes before linearized reading' (duration: 838.884811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:47.109499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:46.27045Z","time spent":"839.039816ms","remote":"127.0.0.1:48422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:53:47.109713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.418684ms","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-10T18:53:47.109742Z","caller":"traceutil/trace.go:171","msg":"trace[87117859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:949; }","duration":"762.472315ms","start":"2026-02-10T18:53:46.347261Z","end":"2026-02-10T18:53:47.109733Z","steps":["trace[87117859] 'agreement among raft nodes before linearized reading' (duration: 762.363322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:47.109769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:46.347247Z","time spent":"762.515636ms","remote":"127.0.0.1:48654","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-10T18:53:47.110077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.822857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:47.110108Z","caller":"traceutil/trace.go:171","msg":"trace[873000075] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:949; }","duration":"109.89669ms","start":"2026-02-10T18:53:47.000201Z","end":"2026-02-10T18:53:47.110098Z","steps":["trace[873000075] 'agreement among raft nodes before linearized reading' (duration: 109.853659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:47.110245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.187166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:53:47.110273Z","caller":"traceutil/trace.go:171","msg":"trace[1190796934] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:949; }","duration":"270.249728ms","start":"2026-02-10T18:53:46.840015Z","end":"2026-02-10T18:53:47.110264Z","steps":["trace[1190796934] 'agreement among raft nodes before linearized reading' (duration: 270.204777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:47.110441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.247527ms","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-10T18:53:47.110468Z","caller":"traceutil/trace.go:171","msg":"trace[915944701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:949; }","duration":"526.306538ms","start":"2026-02-10T18:53:46.584153Z","end":"2026-02-10T18:53:47.11046Z","steps":["trace[915944701] 'agreement among raft nodes before linearized reading' (duration: 526.227546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:47.110495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:46.584135Z","time spent":"526.353439ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:53:47.110652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.44506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-10T18:53:47.110678Z","caller":"traceutil/trace.go:171","msg":"trace[560099335] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:949; }","duration":"760.479681ms","start":"2026-02-10T18:53:46.350191Z","end":"2026-02-10T18:53:47.11067Z","steps":["trace[560099335] 'agreement among raft nodes before linearized reading' (duration: 760.406649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:47.110716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:46.350186Z","time spent":"760.511202ms","remote":"127.0.0.1:48654","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-10T18:53:47.564886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.500087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:53:47.564966Z","caller":"traceutil/trace.go:171","msg":"trace[1367728285] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"443.62015ms","start":"2026-02-10T18:53:47.121334Z","end":"2026-02-10T18:53:47.564954Z","steps":["trace[1367728285] 'read index received' (duration: 237.063561ms)","trace[1367728285] 'applied index is now lower than readState.Index' (duration: 206.555569ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:47.565061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.723354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:47.565081Z","caller":"traceutil/trace.go:171","msg":"trace[1200159462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"443.762624ms","start":"2026-02-10T18:53:47.121312Z","end":"2026-02-10T18:53:47.565074Z","steps":["trace[1200159462] 'agreement among raft nodes before linearized reading' (duration: 443.675152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:47.565132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:47.12129Z","time spent":"443.834987ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:53:47.565331Z","caller":"traceutil/trace.go:171","msg":"trace[1477459425] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"444.975478ms","start":"2026-02-10T18:53:47.120342Z","end":"2026-02-10T18:53:47.565318Z","steps":["trace[1477459425] 'process raft request' (duration: 237.973687ms)","trace[1477459425] 'compare' (duration: 206.423905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:47.565394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:47.120326Z","time spent":"445.031339ms","remote":"127.0.0.1:48442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:47.57011Z","caller":"traceutil/trace.go:171","msg":"trace[1622179505] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"445.078361ms","start":"2026-02-10T18:53:47.12502Z","end":"2026-02-10T18:53:47.570099Z","steps":["trace[1622179505] 'process raft request' (duration: 445.03187ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:47.570146Z","caller":"traceutil/trace.go:171","msg":"trace[468967962] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"447.840747ms","start":"2026-02-10T18:53:47.122281Z","end":"2026-02-10T18:53:47.570122Z","steps":["trace[468967962] 'process raft request' (duration: 447.60401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:47.570187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:47.12501Z","time spent":"445.136542ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:47.570209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:47.12227Z","time spent":"447.902398ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:47.570413Z","caller":"traceutil/trace.go:171","msg":"trace[1140370325] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"446.201031ms","start":"2026-02-10T18:53:47.124204Z","end":"2026-02-10T18:53:47.570405Z","steps":["trace[1140370325] 'process raft request' (duration: 445.813371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:47.570451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:47.124195Z","time spent":"446.234622ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:47.847319Z","caller":"traceutil/trace.go:171","msg":"trace[929040238] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"279.529431ms","start":"2026-02-10T18:53:47.567769Z","end":"2026-02-10T18:53:47.847298Z","steps":["trace[929040238] 'read index received' (duration: 2.342054ms)","trace[929040238] 'applied index is now lower than readState.Index' (duration: 277.186467ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:47.847437Z","caller":"traceutil/trace.go:171","msg":"trace[66831003] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"435.752396ms","start":"2026-02-10T18:53:47.411675Z","end":"2026-02-10T18:53:47.847427Z","steps":["trace[66831003] 'process raft request' (duration: 435.493099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:47.847507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.589313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-10T18:53:47.84752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:47.411651Z","time spent":"435.808618ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:53:47.847551Z","caller":"traceutil/trace.go:171","msg":"trace[1231299586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"279.652235ms","start":"2026-02-10T18:53:47.567887Z","end":"2026-02-10T18:53:47.847539Z","steps":["trace[1231299586] 'agreement among raft nodes before linearized reading' (duration: 279.584733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:47.847714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.941253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-10T18:53:47.847741Z","caller":"traceutil/trace.go:171","msg":"trace[2079233189] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:954; }","duration":"279.991254ms","start":"2026-02-10T18:53:47.567741Z","end":"2026-02-10T18:53:47.847733Z","steps":["trace[2079233189] 'agreement among raft nodes before linearized reading' (duration: 279.941053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:47.847862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.601802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T18:53:47.847896Z","caller":"traceutil/trace.go:171","msg":"trace[998660815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"198.666383ms","start":"2026-02-10T18:53:47.649218Z","end":"2026-02-10T18:53:47.847884Z","steps":["trace[998660815] 'agreement among raft nodes before linearized reading' (duration: 198.561211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:49.469107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402285551s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:53:49.469199Z","caller":"traceutil/trace.go:171","msg":"trace[1583235538] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"1.196687928s","start":"2026-02-10T18:53:48.272499Z","end":"2026-02-10T18:53:49.469187Z","steps":["trace[1583235538] 'read index received' (duration: 35.631µs)","trace[1583235538] 'applied index is now lower than readState.Index' (duration: 1.196651107s)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:49.469211Z","caller":"traceutil/trace.go:171","msg":"trace[904439608] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.615932544s","start":"2026-02-10T18:53:47.853255Z","end":"2026-02-10T18:53:49.469188Z","steps":["trace[904439608] 'process raft request' (duration: 213.512509ms)","trace[904439608] 'compare' (duration: 1.40186283s)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:49.46931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:47.853239Z","time spent":"1.616016396s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:49.469329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196825652s","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-10T18:53:49.46935Z","caller":"traceutil/trace.go:171","msg":"trace[1898221552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"1.196897625s","start":"2026-02-10T18:53:48.272445Z","end":"2026-02-10T18:53:49.469343Z","steps":["trace[1898221552] 'agreement among raft nodes before linearized reading' (duration: 1.196797662s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:49.469373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:48.272428Z","time spent":"1.196937745s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:53:49.469501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.405732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:53:49.469525Z","caller":"traceutil/trace.go:171","msg":"trace[851257851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"563.456232ms","start":"2026-02-10T18:53:48.906062Z","end":"2026-02-10T18:53:49.469518Z","steps":["trace[851257851] 'agreement among raft nodes before linearized reading' (duration: 563.416801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:49.469563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:48.906047Z","time spent":"563.510024ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:53:49.855807Z","caller":"traceutil/trace.go:171","msg":"trace[1615012214] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"281.30148ms","start":"2026-02-10T18:53:49.574483Z","end":"2026-02-10T18:53:49.855785Z","steps":["trace[1615012214] 'read index received' (duration: 250.973062ms)","trace[1615012214] 'applied index is now lower than readState.Index' (duration: 30.326718ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:53:49.855917Z","caller":"traceutil/trace.go:171","msg":"trace[691140268] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"377.811675ms","start":"2026-02-10T18:53:49.478092Z","end":"2026-02-10T18:53:49.855904Z","steps":["trace[691140268] 'process raft request' (duration: 347.381074ms)","trace[691140268] 'compare' (duration: 28.957721ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:53:49.856006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:53:49.478084Z","time spent":"377.863716ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:53:49.856214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.718951ms","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-10T18:53:49.856279Z","caller":"traceutil/trace.go:171","msg":"trace[358780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:956; }","duration":"281.809534ms","start":"2026-02-10T18:53:49.574453Z","end":"2026-02-10T18:53:49.856262Z","steps":["trace[358780] 'agreement among raft nodes before linearized reading' (duration: 281.644849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:49.857835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.304354ms","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-10T18:53:49.857883Z","caller":"traceutil/trace.go:171","msg":"trace[457564173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:957; }","duration":"283.364017ms","start":"2026-02-10T18:53:49.574508Z","end":"2026-02-10T18:53:49.857872Z","steps":["trace[457564173] 'agreement among raft nodes before linearized reading' (duration: 283.226683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:53:49.858212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.541561ms","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-10T18:53:49.858257Z","caller":"traceutil/trace.go:171","msg":"trace[1451243961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:957; }","duration":"283.598703ms","start":"2026-02-10T18:53:49.574649Z","end":"2026-02-10T18:53:49.858248Z","steps":["trace[1451243961] 'agreement among raft nodes before linearized reading' (duration: 283.48402ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:53:59.635954Z","caller":"traceutil/trace.go:171","msg":"trace[396086725] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"103.253949ms","start":"2026-02-10T18:53:59.53268Z","end":"2026-02-10T18:53:59.635934Z","steps":["trace[396086725] 'process raft request' (duration: 103.119895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:01.123725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.081754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:01.123776Z","caller":"traceutil/trace.go:171","msg":"trace[533646175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"203.163827ms","start":"2026-02-10T18:54:00.920599Z","end":"2026-02-10T18:54:01.123763Z","steps":["trace[533646175] 'range keys from in-memory index tree' (duration: 203.029683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:09.590078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191481176s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:54:09.591263Z","caller":"traceutil/trace.go:171","msg":"trace[762663311] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1121; }","duration":"1.320286288s","start":"2026-02-10T18:54:08.269888Z","end":"2026-02-10T18:54:09.590174Z","steps":["trace[762663311] 'read index received' (duration: 128.597346ms)","trace[762663311] 'applied index is now lower than readState.Index' (duration: 1.191687342s)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:09.59141Z","caller":"traceutil/trace.go:171","msg":"trace[1048791506] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"1.324241306s","start":"2026-02-10T18:54:08.267155Z","end":"2026-02-10T18:54:09.591396Z","steps":["trace[1048791506] 'process raft request' (duration: 131.344231ms)","trace[1048791506] 'compare' (duration: 1.190831699s)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:09.591585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:08.267139Z","time spent":"1.32437687s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:54:09.592045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322147079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-10T18:54:09.592162Z","caller":"traceutil/trace.go:171","msg":"trace[321580970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1074; }","duration":"1.322223531s","start":"2026-02-10T18:54:08.269867Z","end":"2026-02-10T18:54:09.592091Z","steps":["trace[321580970] 'agreement among raft nodes before linearized reading' (duration: 1.321992935s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:09.592449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:08.26986Z","time spent":"1.322574021s","remote":"127.0.0.1:48654","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-10T18:54:09.593332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164061787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-02-10T18:54:09.594007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.853776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-10T18:54:09.594782Z","caller":"traceutil/trace.go:171","msg":"trace[302327188] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1074; }","duration":"538.664307ms","start":"2026-02-10T18:54:09.056099Z","end":"2026-02-10T18:54:09.594763Z","steps":["trace[302327188] 'agreement among raft nodes before linearized reading' (duration: 537.793634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:09.595243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:09.056082Z","time spent":"539.149881ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-10T18:54:09.595389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.534511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-10T18:54:09.595449Z","caller":"traceutil/trace.go:171","msg":"trace[1253443697] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1074; }","duration":"821.605393ms","start":"2026-02-10T18:54:08.773813Z","end":"2026-02-10T18:54:09.595419Z","steps":["trace[1253443697] 'agreement among raft nodes before linearized reading' (duration: 821.461029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:09.595484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:08.773796Z","time spent":"821.679856ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-10T18:54:09.595716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.127358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:09.595817Z","caller":"traceutil/trace.go:171","msg":"trace[447559493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1074; }","duration":"239.290523ms","start":"2026-02-10T18:54:09.356518Z","end":"2026-02-10T18:54:09.595808Z","steps":["trace[447559493] 'agreement among raft nodes before linearized reading' (duration: 239.147799ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:09.593571Z","caller":"traceutil/trace.go:171","msg":"trace[1694292409] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1074; }","duration":"1.164313793s","start":"2026-02-10T18:54:08.429163Z","end":"2026-02-10T18:54:09.593477Z","steps":["trace[1694292409] 'agreement among raft nodes before linearized reading' (duration: 1.164092267s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:09.596164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:08.429147Z","time spent":"1.167008057s","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-10T18:54:09.848925Z","caller":"traceutil/trace.go:171","msg":"trace[775488404] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"247.29541ms","start":"2026-02-10T18:54:09.601587Z","end":"2026-02-10T18:54:09.848882Z","steps":["trace[775488404] 'process raft request' (duration: 162.278586ms)","trace[775488404] 'compare' (duration: 84.906791ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:09.852213Z","caller":"traceutil/trace.go:171","msg":"trace[865561156] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"249.939652ms","start":"2026-02-10T18:54:09.601863Z","end":"2026-02-10T18:54:09.851803Z","steps":["trace[865561156] 'process raft request' (duration: 249.811429ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:09.852921Z","caller":"traceutil/trace.go:171","msg":"trace[539716470] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"250.990292ms","start":"2026-02-10T18:54:09.601898Z","end":"2026-02-10T18:54:09.852888Z","steps":["trace[539716470] 'process raft request' (duration: 249.873201ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:09.856435Z","caller":"traceutil/trace.go:171","msg":"trace[431643380] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"254.25305ms","start":"2026-02-10T18:54:09.60217Z","end":"2026-02-10T18:54:09.856423Z","steps":["trace[431643380] 'process raft request' (duration: 250.653332ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:55.075819Z","caller":"traceutil/trace.go:171","msg":"trace[374703975] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"100.682663ms","start":"2026-02-10T18:54:54.975112Z","end":"2026-02-10T18:54:55.075795Z","steps":["trace[374703975] 'process raft request' (duration: 48.962809ms)","trace[374703975] 'compare' (duration: 51.611991ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:55:08.76045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.432852ms","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-10T18:55:08.762152Z","caller":"traceutil/trace.go:171","msg":"trace[69836732] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1317; }","duration":"179.303252ms","start":"2026-02-10T18:55:08.582823Z","end":"2026-02-10T18:55:08.762127Z","steps":["trace[69836732] 'range keys from in-memory index tree' (duration: 177.288017ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:08.870762Z","caller":"traceutil/trace.go:171","msg":"trace[1672066385] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"104.061915ms","start":"2026-02-10T18:55:08.766681Z","end":"2026-02-10T18:55:08.870743Z","steps":["trace[1672066385] 'process raft request' (duration: 103.384897ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:08.873849Z","caller":"traceutil/trace.go:171","msg":"trace[1579169313] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"105.710701ms","start":"2026-02-10T18:55:08.768127Z","end":"2026-02-10T18:55:08.873837Z","steps":["trace[1579169313] 'process raft request' (duration: 105.665009ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:10.210385Z","caller":"traceutil/trace.go:171","msg":"trace[442787150] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"113.286267ms","start":"2026-02-10T18:55:10.097075Z","end":"2026-02-10T18:55:10.210362Z","steps":["trace[442787150] 'process raft request' (duration: 112.796853ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:10.98331Z","caller":"traceutil/trace.go:171","msg":"trace[497914698] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"101.221235ms","start":"2026-02-10T18:55:10.882065Z","end":"2026-02-10T18:55:10.983287Z","steps":["trace[497914698] 'process raft request' (duration: 100.061043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:17.703227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.668899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:55:17.703312Z","caller":"traceutil/trace.go:171","msg":"trace[569414414] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1354; }","duration":"112.812883ms","start":"2026-02-10T18:55:17.590479Z","end":"2026-02-10T18:55:17.703292Z","steps":["trace[569414414] 'count revisions from in-memory index tree' (duration: 112.564816ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:57:23.304561Z","caller":"traceutil/trace.go:171","msg":"trace[1924066133] linearizableReadLoop","detail":"{readStateIndex:1981; appliedIndex:1980; }","duration":"424.870496ms","start":"2026-02-10T18:57:22.879669Z","end":"2026-02-10T18:57:23.304539Z","steps":["trace[1924066133] 'read index received' (duration: 424.677971ms)","trace[1924066133] 'applied index is now lower than readState.Index' (duration: 191.735µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:57:23.304676Z","caller":"traceutil/trace.go:171","msg":"trace[1136973856] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"515.70639ms","start":"2026-02-10T18:57:22.788958Z","end":"2026-02-10T18:57:23.304665Z","steps":["trace[1136973856] 'process raft request' (duration: 515.432923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:57:23.304772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:57:22.788947Z","time spent":"515.754861ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:57:23.305184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.510963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-10T18:57:23.305219Z","caller":"traceutil/trace.go:171","msg":"trace[1792510786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1893; }","duration":"425.576414ms","start":"2026-02-10T18:57:22.879633Z","end":"2026-02-10T18:57:23.305209Z","steps":["trace[1792510786] 'agreement among raft nodes before linearized reading' (duration: 425.341498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:57:23.305246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:57:22.879617Z","time spent":"425.623116ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:57:23.305386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.422891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:57:23.305411Z","caller":"traceutil/trace.go:171","msg":"trace[468943254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1893; }","duration":"397.476952ms","start":"2026-02-10T18:57:22.907926Z","end":"2026-02-10T18:57:23.305402Z","steps":["trace[468943254] 'agreement among raft nodes before linearized reading' (duration: 397.436461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:57:23.305433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:57:22.907904Z","time spent":"397.524034ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:57:23.306255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.849932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-10T18:57:23.306292Z","caller":"traceutil/trace.go:171","msg":"trace[2056038263] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1893; }","duration":"164.934144ms","start":"2026-02-10T18:57:23.141349Z","end":"2026-02-10T18:57:23.306283Z","steps":["trace[2056038263] 'agreement among raft nodes before linearized reading' (duration: 164.846212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:57:23.30652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.992283ms","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-10T18:57:23.306568Z","caller":"traceutil/trace.go:171","msg":"trace[424579947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1893; }","duration":"222.075005ms","start":"2026-02-10T18:57:23.084485Z","end":"2026-02-10T18:57:23.30656Z","steps":["trace[424579947] 'agreement among raft nodes before linearized reading' (duration: 221.960662ms)"],"step_count":1}