{"level":"warn","ts":"2026-01-29T19:00:45.751566Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T19:00:45.751684Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-01-29T19:00:45.75177Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T19:00:45.751786Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-01-29T19:00:45.751834Z","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-01-29T19:00:45.752617Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-01-29T19:00:45.752708Z","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://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-01-29T19:00:45.758093Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.081551ms"} {"level":"info","ts":"2026-01-29T19:00:45.789157Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-01-29T19:00:45.789217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T19:00:45.789236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-01-29T19:00:45.789243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T19:00:45.789252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-01-29T19:00:45.789281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-01-29T19:00:45.801113Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T19:00:45.80808Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T19:00:45.814594Z","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-01-29T19:00:45.816563Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T19:00:45.818337Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-29T19:00:45.818911Z","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-01-29T19:00:45.819179Z","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-01-29T19:00:45.823186Z","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-01-29T19:00:45.843044Z","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-01-29T19:00:45.843231Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T19:00:45.84325Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T19:00:45.843301Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-01-29T19:00:45.843308Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-01-29T19:00:45.821305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-01-29T19:00:45.844212Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-01-29T19:00:46.190874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T19:00:46.190912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T19:00:46.190935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-01-29T19:00:46.190946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-01-29T19:00:46.190952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-01-29T19:00:46.190961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-01-29T19:00:46.190972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-01-29T19:00:46.212769Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T19:00:46.212902Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T19:00:46.21321Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T19:00:46.213301Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T19:00:46.215644Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-01-29T19:00:46.21639Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T19:00:46.216486Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T19:00:46.216511Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T19:00:46.216475Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T19:00:46.216569Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T19:00:46.219483Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-01-29T19:01:09.333434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528823590936745,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T19:01:09.512052Z","caller":"traceutil/trace.go:171","msg":"trace[94847488] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"679.287543ms","start":"2026-01-29T19:01:08.832746Z","end":"2026-01-29T19:01:09.512034Z","steps":["trace[94847488] 'read index received' (duration: 679.165331ms)","trace[94847488] 'applied index is now lower than readState.Index' (duration: 121.122µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:01:09.512173Z","caller":"traceutil/trace.go:171","msg":"trace[915138529] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"752.74676ms","start":"2026-01-29T19:01:08.75942Z","end":"2026-01-29T19:01:09.512167Z","steps":["trace[915138529] 'process raft request' (duration: 752.490437ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:09.512482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.913084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-01-29T19:01:09.512584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:08.759398Z","time spent":"752.79615ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:09.51261Z","caller":"traceutil/trace.go:171","msg":"trace[1852793860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:419; }","duration":"657.059285ms","start":"2026-01-29T19:01:08.855523Z","end":"2026-01-29T19:01:09.512582Z","steps":["trace[1852793860] 'agreement among raft nodes before linearized reading' (duration: 656.867553ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:09.512651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:08.855491Z","time spent":"657.151835ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-29T19:01:09.51278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.033398ms","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-01-29T19:01:09.512796Z","caller":"traceutil/trace.go:171","msg":"trace[879375808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"680.084418ms","start":"2026-01-29T19:01:08.832706Z","end":"2026-01-29T19:01:09.51279Z","steps":["trace[879375808] 'agreement among raft nodes before linearized reading' (duration: 680.051168ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:09.512811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:08.832687Z","time spent":"680.120218ms","remote":"127.0.0.1:49272","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-01-29T19:01:09.512909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.072672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:09.512929Z","caller":"traceutil/trace.go:171","msg":"trace[199937873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"494.108602ms","start":"2026-01-29T19:01:09.018814Z","end":"2026-01-29T19:01:09.512923Z","steps":["trace[199937873] 'agreement among raft nodes before linearized reading' (duration: 494.073772ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:09.512943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:09.018807Z","time spent":"494.132742ms","remote":"127.0.0.1:49004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T19:01:09.513017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.599051ms","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-01-29T19:01:09.513044Z","caller":"traceutil/trace.go:171","msg":"trace[750228227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:419; }","duration":"521.660151ms","start":"2026-01-29T19:01:08.991377Z","end":"2026-01-29T19:01:09.513037Z","steps":["trace[750228227] 'agreement among raft nodes before linearized reading' (duration: 521.605021ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:09.513091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:08.991361Z","time spent":"521.725022ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T19:01:09.513121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.506963ms","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-01-29T19:01:09.513146Z","caller":"traceutil/trace.go:171","msg":"trace[1080066749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:419; }","duration":"639.553284ms","start":"2026-01-29T19:01:08.873586Z","end":"2026-01-29T19:01:09.513139Z","steps":["trace[1080066749] 'agreement among raft nodes before linearized reading' (duration: 639.462223ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:09.513173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:08.873575Z","time spent":"639.589454ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-29T19:01:09.698503Z","caller":"traceutil/trace.go:171","msg":"trace[329819222] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"179.294643ms","start":"2026-01-29T19:01:09.51919Z","end":"2026-01-29T19:01:09.698485Z","steps":["trace[329819222] 'process raft request' (duration: 159.487396ms)","trace[329819222] 'compare' (duration: 19.734986ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:10.323729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.908822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:01:10.323879Z","caller":"traceutil/trace.go:171","msg":"trace[479005945] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:435; }","duration":"200.195357ms","start":"2026-01-29T19:01:10.123671Z","end":"2026-01-29T19:01:10.323867Z","steps":["trace[479005945] 'read index received' (duration: 47.35µs)","trace[479005945] 'applied index is now lower than readState.Index' (duration: 200.147267ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:10.323939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.266537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T19:01:10.323957Z","caller":"traceutil/trace.go:171","msg":"trace[888510789] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:423; }","duration":"200.309768ms","start":"2026-01-29T19:01:10.123641Z","end":"2026-01-29T19:01:10.323951Z","steps":["trace[888510789] 'agreement among raft nodes before linearized reading' (duration: 200.260038ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:10.324191Z","caller":"traceutil/trace.go:171","msg":"trace[2098404465] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"802.708453ms","start":"2026-01-29T19:01:09.521474Z","end":"2026-01-29T19:01:10.324183Z","steps":["trace[2098404465] 'process raft request' (duration: 342.164996ms)","trace[2098404465] 'compare' (duration: 459.810272ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:10.324231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:09.521465Z","time spent":"802.744103ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:10.324314Z","caller":"traceutil/trace.go:171","msg":"trace[290065915] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"799.942441ms","start":"2026-01-29T19:01:09.524366Z","end":"2026-01-29T19:01:10.324308Z","steps":["trace[290065915] 'process raft request' (duration: 799.437907ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:10.324344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:09.524359Z","time spent":"799.968502ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:10.324445Z","caller":"traceutil/trace.go:171","msg":"trace[2122672340] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"800.034543ms","start":"2026-01-29T19:01:09.524403Z","end":"2026-01-29T19:01:10.324437Z","steps":["trace[2122672340] 'process raft request' (duration: 799.435978ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:10.324479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:09.524399Z","time spent":"800.062293ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:11.829045Z","caller":"traceutil/trace.go:171","msg":"trace[289243179] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"123.945901ms","start":"2026-01-29T19:01:11.705082Z","end":"2026-01-29T19:01:11.829028Z","steps":["trace[289243179] 'process raft request' (duration: 123.83265ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:12.538802Z","caller":"traceutil/trace.go:171","msg":"trace[1977702318] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"146.945861ms","start":"2026-01-29T19:01:12.391837Z","end":"2026-01-29T19:01:12.538783Z","steps":["trace[1977702318] 'process raft request' (duration: 105.249417ms)","trace[1977702318] 'compare' (duration: 41.609473ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:18.239394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.445854ms","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-01-29T19:01:18.239453Z","caller":"traceutil/trace.go:171","msg":"trace[2036886377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:471; }","duration":"334.548674ms","start":"2026-01-29T19:01:17.904891Z","end":"2026-01-29T19:01:18.23944Z","steps":["trace[2036886377] 'range keys from in-memory index tree' (duration: 334.310802ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:18.23948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:17.904877Z","time spent":"334.596674ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T19:01:18.239686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.409946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:18.239704Z","caller":"traceutil/trace.go:171","msg":"trace[372927034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"217.451416ms","start":"2026-01-29T19:01:18.022247Z","end":"2026-01-29T19:01:18.239699Z","steps":["trace[372927034] 'range keys from in-memory index tree' (duration: 217.355926ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:18.35067Z","caller":"traceutil/trace.go:171","msg":"trace[306373400] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"107.911118ms","start":"2026-01-29T19:01:18.242734Z","end":"2026-01-29T19:01:18.350645Z","steps":["trace[306373400] 'process raft request' (duration: 107.781087ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:18.72195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.988686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4106"} {"level":"info","ts":"2026-01-29T19:01:18.722046Z","caller":"traceutil/trace.go:171","msg":"trace[2008519739] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:472; }","duration":"168.134417ms","start":"2026-01-29T19:01:18.553892Z","end":"2026-01-29T19:01:18.722027Z","steps":["trace[2008519739] 'range keys from in-memory index tree' (duration: 167.888875ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:18.722316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.687066ms","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-01-29T19:01:18.722356Z","caller":"traceutil/trace.go:171","msg":"trace[713585054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"154.775616ms","start":"2026-01-29T19:01:18.567569Z","end":"2026-01-29T19:01:18.722345Z","steps":["trace[713585054] 'range keys from in-memory index tree' (duration: 154.613616ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:18.722933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.627951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-01-29T19:01:18.722902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.35244ms","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-01-29T19:01:18.722968Z","caller":"traceutil/trace.go:171","msg":"trace[915472753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:472; }","duration":"251.689462ms","start":"2026-01-29T19:01:18.471271Z","end":"2026-01-29T19:01:18.722961Z","steps":["trace[915472753] 'range keys from in-memory index tree' (duration: 251.56931ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:18.723011Z","caller":"traceutil/trace.go:171","msg":"trace[609658839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:472; }","duration":"275.523721ms","start":"2026-01-29T19:01:18.447462Z","end":"2026-01-29T19:01:18.722986Z","steps":["trace[609658839] 'range keys from in-memory index tree' (duration: 275.232699ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:20.502288Z","caller":"traceutil/trace.go:171","msg":"trace[2141261733] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"283.404505ms","start":"2026-01-29T19:01:20.218866Z","end":"2026-01-29T19:01:20.502271Z","steps":["trace[2141261733] 'process raft request' (duration: 283.293374ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:20.503692Z","caller":"traceutil/trace.go:171","msg":"trace[1165787826] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:502; }","duration":"268.834807ms","start":"2026-01-29T19:01:20.234847Z","end":"2026-01-29T19:01:20.503681Z","steps":["trace[1165787826] 'read index received' (duration: 268.826937ms)","trace[1165787826] 'applied index is now lower than readState.Index' (duration: 6.94µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:20.503789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.930217ms","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-01-29T19:01:20.503817Z","caller":"traceutil/trace.go:171","msg":"trace[2130725375] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:484; }","duration":"268.990639ms","start":"2026-01-29T19:01:20.234817Z","end":"2026-01-29T19:01:20.503808Z","steps":["trace[2130725375] 'agreement among raft nodes before linearized reading' (duration: 268.922048ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.503919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.950118ms","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-01-29T19:01:20.503941Z","caller":"traceutil/trace.go:171","msg":"trace[1626854258] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:484; }","duration":"268.996759ms","start":"2026-01-29T19:01:20.234939Z","end":"2026-01-29T19:01:20.503936Z","steps":["trace[1626854258] 'agreement among raft nodes before linearized reading' (duration: 268.964898ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.504049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.077435ms","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-01-29T19:01:20.504072Z","caller":"traceutil/trace.go:171","msg":"trace[1868477567] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:484; }","duration":"113.132027ms","start":"2026-01-29T19:01:20.390933Z","end":"2026-01-29T19:01:20.504065Z","steps":["trace[1868477567] 'agreement among raft nodes before linearized reading' (duration: 113.102656ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.504191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.593725ms","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-01-29T19:01:20.504213Z","caller":"traceutil/trace.go:171","msg":"trace[537006738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:484; }","duration":"149.645836ms","start":"2026-01-29T19:01:20.354562Z","end":"2026-01-29T19:01:20.504208Z","steps":["trace[537006738] 'agreement among raft nodes before linearized reading' (duration: 149.569235ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.5043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.669536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:20.504324Z","caller":"traceutil/trace.go:171","msg":"trace[882303426] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:484; }","duration":"195.692336ms","start":"2026-01-29T19:01:20.308626Z","end":"2026-01-29T19:01:20.504318Z","steps":["trace[882303426] 'agreement among raft nodes before linearized reading' (duration: 195.657306ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.504393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.783008ms","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-01-29T19:01:20.504414Z","caller":"traceutil/trace.go:171","msg":"trace[1260958678] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:484; }","duration":"195.828298ms","start":"2026-01-29T19:01:20.30858Z","end":"2026-01-29T19:01:20.504409Z","steps":["trace[1260958678] 'agreement among raft nodes before linearized reading' (duration: 195.798338ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.504481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.159728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:20.504501Z","caller":"traceutil/trace.go:171","msg":"trace[1881761572] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:484; }","duration":"239.204839ms","start":"2026-01-29T19:01:20.265291Z","end":"2026-01-29T19:01:20.504496Z","steps":["trace[1881761572] 'agreement among raft nodes before linearized reading' (duration: 239.177388ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.504567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.393416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:20.504588Z","caller":"traceutil/trace.go:171","msg":"trace[368238651] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:484; }","duration":"256.602097ms","start":"2026-01-29T19:01:20.24798Z","end":"2026-01-29T19:01:20.504582Z","steps":["trace[368238651] 'agreement among raft nodes before linearized reading' (duration: 256.575257ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.504686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.96827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-fjdkf\" ","response":"range_response_count:1 size:4635"} {"level":"info","ts":"2026-01-29T19:01:20.504708Z","caller":"traceutil/trace.go:171","msg":"trace[1149086602] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-fjdkf; range_end:; response_count:1; response_revision:484; }","duration":"257.00811ms","start":"2026-01-29T19:01:20.247694Z","end":"2026-01-29T19:01:20.504702Z","steps":["trace[1149086602] 'agreement among raft nodes before linearized reading' (duration: 256.95237ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.51392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.899442ms","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-01-29T19:01:20.515005Z","caller":"traceutil/trace.go:171","msg":"trace[563110256] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:484; }","duration":"279.998059ms","start":"2026-01-29T19:01:20.234998Z","end":"2026-01-29T19:01:20.514996Z","steps":["trace[563110256] 'agreement among raft nodes before linearized reading' (duration: 278.856481ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.513958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.972893ms","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-01-29T19:01:20.515074Z","caller":"traceutil/trace.go:171","msg":"trace[2115925826] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:484; }","duration":"280.095091ms","start":"2026-01-29T19:01:20.234975Z","end":"2026-01-29T19:01:20.51507Z","steps":["trace[2115925826] 'agreement among raft nodes before linearized reading' (duration: 278.967793ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.878202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.350846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:01:20.878271Z","caller":"traceutil/trace.go:171","msg":"trace[1357043663] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:507; }","duration":"286.683458ms","start":"2026-01-29T19:01:20.591576Z","end":"2026-01-29T19:01:20.87826Z","steps":["trace[1357043663] 'read index received' (duration: 157.241592ms)","trace[1357043663] 'applied index is now lower than readState.Index' (duration: 129.441116ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:01:20.87842Z","caller":"traceutil/trace.go:171","msg":"trace[588687931] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"306.504244ms","start":"2026-01-29T19:01:20.571906Z","end":"2026-01-29T19:01:20.878411Z","steps":["trace[588687931] 'process raft request' (duration: 176.890797ms)","trace[588687931] 'compare' (duration: 129.278815ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:20.878464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:20.571895Z","time spent":"306.548555ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4609,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:01:20.878578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.006021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:20.878619Z","caller":"traceutil/trace.go:171","msg":"trace[21270930] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:490; }","duration":"287.066171ms","start":"2026-01-29T19:01:20.591546Z","end":"2026-01-29T19:01:20.878612Z","steps":["trace[21270930] 'agreement among raft nodes before linearized reading' (duration: 287.01276ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:20.974959Z","caller":"traceutil/trace.go:171","msg":"trace[2073744638] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"182.70984ms","start":"2026-01-29T19:01:20.792231Z","end":"2026-01-29T19:01:20.97494Z","steps":["trace[2073744638] 'process raft request' (duration: 182.61249ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.975439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.090431ms","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-01-29T19:01:20.975472Z","caller":"traceutil/trace.go:171","msg":"trace[172970300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:491; }","duration":"230.166101ms","start":"2026-01-29T19:01:20.745297Z","end":"2026-01-29T19:01:20.975463Z","steps":["trace[172970300] 'agreement among raft nodes before linearized reading' (duration: 230.03374ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.975596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.239708ms","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-01-29T19:01:20.975613Z","caller":"traceutil/trace.go:171","msg":"trace[588276372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"220.268418ms","start":"2026-01-29T19:01:20.755339Z","end":"2026-01-29T19:01:20.975608Z","steps":["trace[588276372] 'agreement among raft nodes before linearized reading' (duration: 220.219298ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:20.975692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.4022ms","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-01-29T19:01:20.975707Z","caller":"traceutil/trace.go:171","msg":"trace[1413918079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"220.43237ms","start":"2026-01-29T19:01:20.75527Z","end":"2026-01-29T19:01:20.975703Z","steps":["trace[1413918079] 'agreement among raft nodes before linearized reading' (duration: 220.405509ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:21.082355Z","caller":"traceutil/trace.go:171","msg":"trace[27831123] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"198.03861ms","start":"2026-01-29T19:01:20.884297Z","end":"2026-01-29T19:01:21.082336Z","steps":["trace[27831123] 'process raft request' (duration: 194.703246ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:21.108552Z","caller":"traceutil/trace.go:171","msg":"trace[1315736588] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"115.714821ms","start":"2026-01-29T19:01:20.992825Z","end":"2026-01-29T19:01:21.10854Z","steps":["trace[1315736588] 'process raft request' (duration: 115.69434ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:21.10877Z","caller":"traceutil/trace.go:171","msg":"trace[716664221] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"129.481862ms","start":"2026-01-29T19:01:20.979277Z","end":"2026-01-29T19:01:21.108759Z","steps":["trace[716664221] 'process raft request' (duration: 129.074759ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:21.109025Z","caller":"traceutil/trace.go:171","msg":"trace[1529168033] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"128.932789ms","start":"2026-01-29T19:01:20.980084Z","end":"2026-01-29T19:01:21.109017Z","steps":["trace[1529168033] 'process raft request' (duration: 128.326745ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:21.109222Z","caller":"traceutil/trace.go:171","msg":"trace[875479474] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"129.030949ms","start":"2026-01-29T19:01:20.980184Z","end":"2026-01-29T19:01:21.109215Z","steps":["trace[875479474] 'process raft request' (duration: 128.260884ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:21.1093Z","caller":"traceutil/trace.go:171","msg":"trace[42471295] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"126.994774ms","start":"2026-01-29T19:01:20.9823Z","end":"2026-01-29T19:01:21.109295Z","steps":["trace[42471295] 'process raft request' (duration: 126.168838ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:21.10937Z","caller":"traceutil/trace.go:171","msg":"trace[797152465] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"126.285828ms","start":"2026-01-29T19:01:20.983076Z","end":"2026-01-29T19:01:21.109362Z","steps":["trace[797152465] 'process raft request' (duration: 125.425712ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:21.276137Z","caller":"traceutil/trace.go:171","msg":"trace[409528138] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"123.619658ms","start":"2026-01-29T19:01:21.152498Z","end":"2026-01-29T19:01:21.276118Z","steps":["trace[409528138] 'process raft request' (duration: 121.782005ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:21.367872Z","caller":"traceutil/trace.go:171","msg":"trace[231672280] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"172.883901ms","start":"2026-01-29T19:01:21.194971Z","end":"2026-01-29T19:01:21.367855Z","steps":["trace[231672280] 'process raft request' (duration: 172.807661ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:21.497396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.572985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:21.497233Z","caller":"traceutil/trace.go:171","msg":"trace[659530440] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"102.408934ms","start":"2026-01-29T19:01:21.394796Z","end":"2026-01-29T19:01:21.497205Z","steps":["trace[659530440] 'read index received' (duration: 72.379953ms)","trace[659530440] 'applied index is now lower than readState.Index' (duration: 30.026941ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:01:21.497749Z","caller":"traceutil/trace.go:171","msg":"trace[1469650113] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:505; }","duration":"102.688195ms","start":"2026-01-29T19:01:21.394774Z","end":"2026-01-29T19:01:21.497463Z","steps":["trace[1469650113] 'agreement among raft nodes before linearized reading' (duration: 102.473614ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:21.498197Z","caller":"traceutil/trace.go:171","msg":"trace[1482843824] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"105.949739ms","start":"2026-01-29T19:01:21.392216Z","end":"2026-01-29T19:01:21.498165Z","steps":["trace[1482843824] 'process raft request' (duration: 104.308618ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:21.833898Z","caller":"traceutil/trace.go:171","msg":"trace[697106295] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"357.554939ms","start":"2026-01-29T19:01:21.476315Z","end":"2026-01-29T19:01:21.833869Z","steps":["trace[697106295] 'process raft request' (duration: 298.158512ms)","trace[697106295] 'compare' (duration: 59.233325ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:21.834184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:21.476301Z","time spent":"357.76303ms","remote":"127.0.0.1:49040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:21.856462Z","caller":"traceutil/trace.go:171","msg":"trace[411743522] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:523; }","duration":"250.900863ms","start":"2026-01-29T19:01:21.605543Z","end":"2026-01-29T19:01:21.856444Z","steps":["trace[411743522] 'read index received' (duration: 168.950791ms)","trace[411743522] 'applied index is now lower than readState.Index' (duration: 81.949452ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:21.856615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.054064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:21.856644Z","caller":"traceutil/trace.go:171","msg":"trace[1362691117] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:508; }","duration":"251.119575ms","start":"2026-01-29T19:01:21.605515Z","end":"2026-01-29T19:01:21.856635Z","steps":["trace[1362691117] 'agreement among raft nodes before linearized reading' (duration: 250.998094ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:21.856795Z","caller":"traceutil/trace.go:171","msg":"trace[705891703] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"347.782166ms","start":"2026-01-29T19:01:21.508996Z","end":"2026-01-29T19:01:21.856778Z","steps":["trace[705891703] 'process raft request' (duration: 347.323513ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:21.856866Z","caller":"traceutil/trace.go:171","msg":"trace[366008940] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"263.875179ms","start":"2026-01-29T19:01:21.592981Z","end":"2026-01-29T19:01:21.856857Z","steps":["trace[366008940] 'process raft request' (duration: 263.439586ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:21.856891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:21.50898Z","time spent":"347.853926ms","remote":"127.0.0.1:49040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:22.215712Z","caller":"traceutil/trace.go:171","msg":"trace[42830906] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"422.86186ms","start":"2026-01-29T19:01:21.792833Z","end":"2026-01-29T19:01:22.215695Z","steps":["trace[42830906] 'process raft request' (duration: 338.542783ms)","trace[42830906] 'compare' (duration: 84.242266ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:22.215792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:21.792811Z","time spent":"422.94446ms","remote":"127.0.0.1:49040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T19:01:22.216199Z","caller":"traceutil/trace.go:171","msg":"trace[389987752] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:526; }","duration":"343.797239ms","start":"2026-01-29T19:01:21.872396Z","end":"2026-01-29T19:01:22.216193Z","steps":["trace[389987752] 'read index received' (duration: 258.989908ms)","trace[389987752] 'applied index is now lower than readState.Index' (duration: 84.806951ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:01:22.216314Z","caller":"traceutil/trace.go:171","msg":"trace[1011818628] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"346.68189ms","start":"2026-01-29T19:01:21.869628Z","end":"2026-01-29T19:01:22.21631Z","steps":["trace[1011818628] 'process raft request' (duration: 346.487459ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:22.216349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:21.869609Z","time spent":"346.715921ms","remote":"127.0.0.1:49040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5162,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:22.216404Z","caller":"traceutil/trace.go:171","msg":"trace[1509755755] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"346.366649ms","start":"2026-01-29T19:01:21.870033Z","end":"2026-01-29T19:01:22.2164Z","steps":["trace[1509755755] 'process raft request' (duration: 346.122017ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:22.216429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:21.86999Z","time spent":"346.423199ms","remote":"127.0.0.1:49040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:22.216522Z","caller":"traceutil/trace.go:171","msg":"trace[1929247790] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"218.546288ms","start":"2026-01-29T19:01:21.99797Z","end":"2026-01-29T19:01:22.216516Z","steps":["trace[1929247790] 'process raft request' (duration: 218.206196ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:22.216584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.187242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:22.2166Z","caller":"traceutil/trace.go:171","msg":"trace[728117157] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:512; }","duration":"344.229852ms","start":"2026-01-29T19:01:21.872365Z","end":"2026-01-29T19:01:22.216595Z","steps":["trace[728117157] 'agreement among raft nodes before linearized reading' (duration: 344.202472ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:22.216612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:21.872351Z","time spent":"344.258372ms","remote":"127.0.0.1:49090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-01-29T19:01:22.636578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.589975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:01:22.63703Z","caller":"traceutil/trace.go:171","msg":"trace[265413191] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"378.962559ms","start":"2026-01-29T19:01:22.258057Z","end":"2026-01-29T19:01:22.63702Z","steps":["trace[265413191] 'process raft request' (duration: 378.935489ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:22.639354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:22.258053Z","time spent":"381.272386ms","remote":"127.0.0.1:49040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:22.637555Z","caller":"traceutil/trace.go:171","msg":"trace[198903109] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"444.54009ms","start":"2026-01-29T19:01:22.192985Z","end":"2026-01-29T19:01:22.637525Z","steps":["trace[198903109] 'process raft request' (duration: 219.965469ms)","trace[198903109] 'compare' (duration: 223.460893ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:22.639675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:22.192971Z","time spent":"446.543345ms","remote":"127.0.0.1:49040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T19:01:22.638124Z","caller":"traceutil/trace.go:171","msg":"trace[1917957239] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"421.895674ms","start":"2026-01-29T19:01:22.216215Z","end":"2026-01-29T19:01:22.638111Z","steps":["trace[1917957239] 'read index received' (duration: 196.737389ms)","trace[1917957239] 'applied index is now lower than readState.Index' (duration: 225.156975ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:22.638222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.493205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:22.639789Z","caller":"traceutil/trace.go:171","msg":"trace[395590737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"626.123307ms","start":"2026-01-29T19:01:22.013656Z","end":"2026-01-29T19:01:22.639779Z","steps":["trace[395590737] 'agreement among raft nodes before linearized reading' (duration: 624.495665ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:22.639825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:22.013618Z","time spent":"626.200579ms","remote":"127.0.0.1:49004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T19:01:22.638269Z","caller":"traceutil/trace.go:171","msg":"trace[2010509150] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"380.32257ms","start":"2026-01-29T19:01:22.257938Z","end":"2026-01-29T19:01:22.63826Z","steps":["trace[2010509150] 'process raft request' (duration: 378.83038ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:22.639946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:22.257924Z","time spent":"382.003302ms","remote":"127.0.0.1:49040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:22.656424Z","caller":"traceutil/trace.go:171","msg":"trace[692710484] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"237.382035ms","start":"2026-01-29T19:01:22.419031Z","end":"2026-01-29T19:01:22.656413Z","steps":["trace[692710484] 'process raft request' (duration: 236.888932ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:22.656728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.80113ms","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-01-29T19:01:22.656782Z","caller":"traceutil/trace.go:171","msg":"trace[1685695709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:517; }","duration":"396.872231ms","start":"2026-01-29T19:01:22.259904Z","end":"2026-01-29T19:01:22.656777Z","steps":["trace[1685695709] 'agreement among raft nodes before linearized reading' (duration: 396.76014ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:22.656715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.811455ms","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-01-29T19:01:22.656855Z","caller":"traceutil/trace.go:171","msg":"trace[974850132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"101.986636ms","start":"2026-01-29T19:01:22.554861Z","end":"2026-01-29T19:01:22.656848Z","steps":["trace[974850132] 'agreement among raft nodes before linearized reading' (duration: 101.753535ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:22.656812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:22.259899Z","time spent":"396.907451ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-29T19:01:23.23764Z","caller":"traceutil/trace.go:171","msg":"trace[629564616] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"203.109207ms","start":"2026-01-29T19:01:23.034517Z","end":"2026-01-29T19:01:23.237626Z","steps":["trace[629564616] 'process raft request' (duration: 203.016406ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:23.237918Z","caller":"traceutil/trace.go:171","msg":"trace[1881782848] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:545; }","duration":"189.574218ms","start":"2026-01-29T19:01:23.048324Z","end":"2026-01-29T19:01:23.237898Z","steps":["trace[1881782848] 'read index received' (duration: 189.566318ms)","trace[1881782848] 'applied index is now lower than readState.Index' (duration: 6.33µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:23.238081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.737309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:23.238105Z","caller":"traceutil/trace.go:171","msg":"trace[420486957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"189.803699ms","start":"2026-01-29T19:01:23.048293Z","end":"2026-01-29T19:01:23.238097Z","steps":["trace[420486957] 'agreement among raft nodes before linearized reading' (duration: 189.732599ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:23.256147Z","caller":"traceutil/trace.go:171","msg":"trace[1546115465] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"180.853456ms","start":"2026-01-29T19:01:23.075277Z","end":"2026-01-29T19:01:23.256131Z","steps":["trace[1546115465] 'process raft request' (duration: 180.377452ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:23.257203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.397493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-01-29T19:01:23.257277Z","caller":"traceutil/trace.go:171","msg":"trace[186352909] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:529; }","duration":"198.495824ms","start":"2026-01-29T19:01:23.058766Z","end":"2026-01-29T19:01:23.257262Z","steps":["trace[186352909] 'agreement among raft nodes before linearized reading' (duration: 198.337782ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:23.257988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.548762ms","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-01-29T19:01:23.258009Z","caller":"traceutil/trace.go:171","msg":"trace[1353898461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"136.574763ms","start":"2026-01-29T19:01:23.121427Z","end":"2026-01-29T19:01:23.258002Z","steps":["trace[1353898461] 'agreement among raft nodes before linearized reading' (duration: 136.521912ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:23.258124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.697354ms","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-01-29T19:01:23.25814Z","caller":"traceutil/trace.go:171","msg":"trace[562708958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"136.719915ms","start":"2026-01-29T19:01:23.121415Z","end":"2026-01-29T19:01:23.258135Z","steps":["trace[562708958] 'agreement among raft nodes before linearized reading' (duration: 136.676544ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:23.258236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.831575ms","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-01-29T19:01:23.258251Z","caller":"traceutil/trace.go:171","msg":"trace[1961851908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"136.869595ms","start":"2026-01-29T19:01:23.121377Z","end":"2026-01-29T19:01:23.258246Z","steps":["trace[1961851908] 'agreement among raft nodes before linearized reading' (duration: 136.822525ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:23.536061Z","caller":"traceutil/trace.go:171","msg":"trace[1475537282] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"125.824346ms","start":"2026-01-29T19:01:23.410222Z","end":"2026-01-29T19:01:23.536046Z","steps":["trace[1475537282] 'process raft request' (duration: 125.731755ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:23.547632Z","caller":"traceutil/trace.go:171","msg":"trace[114100839] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"106.427544ms","start":"2026-01-29T19:01:23.441191Z","end":"2026-01-29T19:01:23.547619Z","steps":["trace[114100839] 'process raft request' (duration: 104.183427ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:24.313962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528823590936962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:01:24.814533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528823590936962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:01:24.86064Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.159620881s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T19:01:24.896301Z","caller":"traceutil/trace.go:171","msg":"trace[361410349] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"1.083433495s","start":"2026-01-29T19:01:23.812852Z","end":"2026-01-29T19:01:24.896285Z","steps":["trace[361410349] 'read index received' (duration: 1.047860298s)","trace[361410349] 'applied index is now lower than readState.Index' (duration: 35.572567ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:01:24.896468Z","caller":"traceutil/trace.go:171","msg":"trace[1925437859] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.195466069s","start":"2026-01-29T19:01:23.700961Z","end":"2026-01-29T19:01:24.896427Z","steps":["trace[1925437859] 'process raft request' (duration: 1.159806032s)","trace[1925437859] 'compare' (duration: 35.448535ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:24.896593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083732737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-01-29T19:01:24.896665Z","caller":"traceutil/trace.go:171","msg":"trace[2023186109] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:540; }","duration":"1.083841487s","start":"2026-01-29T19:01:23.812815Z","end":"2026-01-29T19:01:24.896657Z","steps":["trace[2023186109] 'agreement among raft nodes before linearized reading' (duration: 1.083619636s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:24.896732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:23.812798Z","time spent":"1.083913508s","remote":"127.0.0.1:49040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10401,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-01-29T19:01:24.89681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.963607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-01-29T19:01:24.896869Z","caller":"traceutil/trace.go:171","msg":"trace[1444722826] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:540; }","duration":"905.046439ms","start":"2026-01-29T19:01:23.991813Z","end":"2026-01-29T19:01:24.89686Z","steps":["trace[1444722826] 'agreement among raft nodes before linearized reading' (duration: 904.918057ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:24.89691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:23.991798Z","time spent":"905.092459ms","remote":"127.0.0.1:49040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4357,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-01-29T19:01:24.897332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.891276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-01-29T19:01:24.897426Z","caller":"traceutil/trace.go:171","msg":"trace[1086049972] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:540; }","duration":"105.949067ms","start":"2026-01-29T19:01:24.791418Z","end":"2026-01-29T19:01:24.897367Z","steps":["trace[1086049972] 'agreement among raft nodes before linearized reading' (duration: 105.811716ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:24.898533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.75616ms","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-01-29T19:01:24.898653Z","caller":"traceutil/trace.go:171","msg":"trace[1149509467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"125.893211ms","start":"2026-01-29T19:01:24.772745Z","end":"2026-01-29T19:01:24.898639Z","steps":["trace[1149509467] 'agreement among raft nodes before linearized reading' (duration: 124.853004ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:24.896667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:23.700945Z","time spent":"1.19562103s","remote":"127.0.0.1:49384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1109,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-29T19:01:24.899072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.412965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:24.899183Z","caller":"traceutil/trace.go:171","msg":"trace[1043563849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"126.523055ms","start":"2026-01-29T19:01:24.772582Z","end":"2026-01-29T19:01:24.899105Z","steps":["trace[1043563849] 'agreement among raft nodes before linearized reading' (duration: 126.427595ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:24.899621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.633076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-01-29T19:01:24.899762Z","caller":"traceutil/trace.go:171","msg":"trace[1618312403] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:540; }","duration":"307.715117ms","start":"2026-01-29T19:01:24.591942Z","end":"2026-01-29T19:01:24.899658Z","steps":["trace[1618312403] 'agreement among raft nodes before linearized reading' (duration: 307.555005ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:24.899936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:24.591925Z","time spent":"307.949709ms","remote":"127.0.0.1:49040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188276,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-01-29T19:01:24.901609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.99961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-01-29T19:01:24.901754Z","caller":"traceutil/trace.go:171","msg":"trace[2039743881] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:540; }","duration":"510.228471ms","start":"2026-01-29T19:01:24.391511Z","end":"2026-01-29T19:01:24.901739Z","steps":["trace[2039743881] 'agreement among raft nodes before linearized reading' (duration: 509.902109ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:24.901982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:24.391487Z","time spent":"510.386812ms","remote":"127.0.0.1:49040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-01-29T19:01:24.902341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.154594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-01-29T19:01:24.902373Z","caller":"traceutil/trace.go:171","msg":"trace[1494527113] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:540; }","duration":"711.216425ms","start":"2026-01-29T19:01:24.191148Z","end":"2026-01-29T19:01:24.902365Z","steps":["trace[1494527113] 'agreement among raft nodes before linearized reading' (duration: 711.106954ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:24.902454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:24.191134Z","time spent":"711.300476ms","remote":"127.0.0.1:49040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24268,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-01-29T19:01:24.902589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.322635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:24.902619Z","caller":"traceutil/trace.go:171","msg":"trace[1312852239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"885.395585ms","start":"2026-01-29T19:01:24.017214Z","end":"2026-01-29T19:01:24.902609Z","steps":["trace[1312852239] 'agreement among raft nodes before linearized reading' (duration: 885.324655ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:24.902682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:24.017206Z","time spent":"885.429617ms","remote":"127.0.0.1:49014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T19:01:32.373303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081906498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bnr7l\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-01-29T19:01:32.37336Z","caller":"traceutil/trace.go:171","msg":"trace[606240533] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bnr7l; range_end:; response_count:1; response_revision:578; }","duration":"1.08204077s","start":"2026-01-29T19:01:31.291307Z","end":"2026-01-29T19:01:32.373348Z","steps":["trace[606240533] 'range keys from in-memory index tree' (duration: 1.081721607s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:32.373408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:31.291292Z","time spent":"1.08210907s","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16676,"request content":"key:\"/registry/pods/kube-system/cilium-bnr7l\" "} {"level":"warn","ts":"2026-01-29T19:01:32.373649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347507661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:32.373668Z","caller":"traceutil/trace.go:171","msg":"trace[976455645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"1.347526751s","start":"2026-01-29T19:01:31.026135Z","end":"2026-01-29T19:01:32.373662Z","steps":["trace[976455645] 'range keys from in-memory index tree' (duration: 1.347444679s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:32.373685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:31.026081Z","time spent":"1.347600161s","remote":"127.0.0.1:49004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T19:01:32.374383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018103902s","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-01-29T19:01:32.374423Z","caller":"traceutil/trace.go:171","msg":"trace[824880385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"1.018164093s","start":"2026-01-29T19:01:31.356249Z","end":"2026-01-29T19:01:32.374413Z","steps":["trace[824880385] 'range keys from in-memory index tree' (duration: 1.018027742s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:32.374442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:31.356236Z","time spent":"1.018201123s","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T19:01:32.374555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013577011s","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-01-29T19:01:32.374573Z","caller":"traceutil/trace.go:171","msg":"trace[1809365540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"1.013622521s","start":"2026-01-29T19:01:31.360944Z","end":"2026-01-29T19:01:32.374567Z","steps":["trace[1809365540] 'range keys from in-memory index tree' (duration: 1.013489059s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:32.374591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:31.360926Z","time spent":"1.013661921s","remote":"127.0.0.1:49272","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-01-29T19:01:32.37469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013498049s","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-01-29T19:01:32.374707Z","caller":"traceutil/trace.go:171","msg":"trace[1257807296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"1.01352977s","start":"2026-01-29T19:01:31.361172Z","end":"2026-01-29T19:01:32.374702Z","steps":["trace[1257807296] 'range keys from in-memory index tree' (duration: 1.013411879s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:32.374723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:31.361164Z","time spent":"1.01355521s","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-29T19:01:33.304358Z","caller":"traceutil/trace.go:171","msg":"trace[1878304524] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"288.706526ms","start":"2026-01-29T19:01:33.015637Z","end":"2026-01-29T19:01:33.304344Z","steps":["trace[1878304524] 'process raft request' (duration: 288.681736ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:01:33.304562Z","caller":"traceutil/trace.go:171","msg":"trace[1325298499] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"922.268795ms","start":"2026-01-29T19:01:32.382287Z","end":"2026-01-29T19:01:33.304556Z","steps":["trace[1325298499] 'process raft request' (duration: 902.153896ms)","trace[1325298499] 'compare' (duration: 18.954431ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:33.304633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:32.382279Z","time spent":"922.295215ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:33.304694Z","caller":"traceutil/trace.go:171","msg":"trace[1577063886] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"922.345146ms","start":"2026-01-29T19:01:32.382344Z","end":"2026-01-29T19:01:33.304689Z","steps":["trace[1577063886] 'process raft request' (duration: 921.858423ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:33.304717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:32.382339Z","time spent":"922.364726ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:33.304822Z","caller":"traceutil/trace.go:171","msg":"trace[585827031] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"901.515531ms","start":"2026-01-29T19:01:32.403302Z","end":"2026-01-29T19:01:33.304817Z","steps":["trace[585827031] 'process raft request' (duration: 900.976067ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:33.304844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:32.403287Z","time spent":"901.545001ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:33.304893Z","caller":"traceutil/trace.go:171","msg":"trace[1319061712] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:598; }","duration":"893.118922ms","start":"2026-01-29T19:01:32.411769Z","end":"2026-01-29T19:01:33.304888Z","steps":["trace[1319061712] 'read index received' (duration: 19.090333ms)","trace[1319061712] 'applied index is now lower than readState.Index' (duration: 874.028109ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:33.305357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.572565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:33.305416Z","caller":"traceutil/trace.go:171","msg":"trace[360191978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"893.647586ms","start":"2026-01-29T19:01:32.411745Z","end":"2026-01-29T19:01:33.305393Z","steps":["trace[360191978] 'agreement among raft nodes before linearized reading' (duration: 893.542615ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:33.305443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:32.411731Z","time spent":"893.706596ms","remote":"127.0.0.1:49004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T19:01:33.30591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.644835ms","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-01-29T19:01:33.305927Z","caller":"traceutil/trace.go:171","msg":"trace[346347439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"341.661965ms","start":"2026-01-29T19:01:32.964259Z","end":"2026-01-29T19:01:33.305921Z","steps":["trace[346347439] 'agreement among raft nodes before linearized reading' (duration: 341.508764ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:33.305944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:32.964209Z","time spent":"341.731875ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T19:01:33.306144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.901546ms","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-01-29T19:01:33.306165Z","caller":"traceutil/trace.go:171","msg":"trace[126658458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:583; }","duration":"318.940896ms","start":"2026-01-29T19:01:32.987213Z","end":"2026-01-29T19:01:33.306154Z","steps":["trace[126658458] 'agreement among raft nodes before linearized reading' (duration: 318.885676ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:33.30618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:32.987198Z","time spent":"318.976576ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-29T19:01:34.811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.716129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:34.811071Z","caller":"traceutil/trace.go:171","msg":"trace[1283160428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"798.819539ms","start":"2026-01-29T19:01:34.012233Z","end":"2026-01-29T19:01:34.811053Z","steps":["trace[1283160428] 'range keys from in-memory index tree' (duration: 798.664648ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:34.811105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:34.012221Z","time spent":"798.876139ms","remote":"127.0.0.1:49004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T19:01:34.811457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.247392ms","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-01-29T19:01:34.811484Z","caller":"traceutil/trace.go:171","msg":"trace[995959893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"377.274052ms","start":"2026-01-29T19:01:34.434202Z","end":"2026-01-29T19:01:34.811476Z","steps":["trace[995959893] 'range keys from in-memory index tree' (duration: 377.136861ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:34.811507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:34.434165Z","time spent":"377.336942ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-29T19:01:35.529225Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528823590937094,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:01:36.030078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528823590937094,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:01:36.0517Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.228646844s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T19:01:36.052264Z","caller":"traceutil/trace.go:171","msg":"trace[2114884460] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.229319509s","start":"2026-01-29T19:01:34.822931Z","end":"2026-01-29T19:01:36.05225Z","steps":["trace[2114884460] 'process raft request' (duration: 1.229231459s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:36.052333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:34.822915Z","time spent":"1.22937344s","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:01:36.341628Z","caller":"traceutil/trace.go:171","msg":"trace[2074041574] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"1.313181556s","start":"2026-01-29T19:01:35.02843Z","end":"2026-01-29T19:01:36.341612Z","steps":["trace[2074041574] 'read index received' (duration: 1.02411269s)","trace[2074041574] 'applied index is now lower than readState.Index' (duration: 289.067926ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:01:36.34174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313294047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:01:36.341757Z","caller":"traceutil/trace.go:171","msg":"trace[1992439847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"1.313347817s","start":"2026-01-29T19:01:35.028404Z","end":"2026-01-29T19:01:36.341751Z","steps":["trace[1992439847] 'agreement among raft nodes before linearized reading' (duration: 1.313299287s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:36.341774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:35.028389Z","time spent":"1.313381877s","remote":"127.0.0.1:49004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T19:01:36.341955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.734841ms","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-01-29T19:01:36.341975Z","caller":"traceutil/trace.go:171","msg":"trace[480405214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"997.780012ms","start":"2026-01-29T19:01:35.34419Z","end":"2026-01-29T19:01:36.34197Z","steps":["trace[480405214] 'agreement among raft nodes before linearized reading' (duration: 997.699871ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:36.341991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:35.344179Z","time spent":"997.807832ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T19:01:36.342117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.039422ms","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-01-29T19:01:36.342131Z","caller":"traceutil/trace.go:171","msg":"trace[18488352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:586; }","duration":"992.061902ms","start":"2026-01-29T19:01:35.350064Z","end":"2026-01-29T19:01:36.342126Z","steps":["trace[18488352] 'agreement among raft nodes before linearized reading' (duration: 992.020281ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:36.342142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:35.350059Z","time spent":"992.080072ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-29T19:01:36.34222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.950808ms","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-01-29T19:01:36.342233Z","caller":"traceutil/trace.go:171","msg":"trace[456260998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"995.976318ms","start":"2026-01-29T19:01:35.346253Z","end":"2026-01-29T19:01:36.34223Z","steps":["trace[456260998] 'agreement among raft nodes before linearized reading' (duration: 995.938648ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:36.342246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:35.34625Z","time spent":"995.991828ms","remote":"127.0.0.1:49272","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-01-29T19:01:36.342336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.091149ms","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-01-29T19:01:36.34235Z","caller":"traceutil/trace.go:171","msg":"trace[1493100235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"996.115319ms","start":"2026-01-29T19:01:35.346231Z","end":"2026-01-29T19:01:36.342346Z","steps":["trace[1493100235] 'agreement among raft nodes before linearized reading' (duration: 996.072349ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:01:36.342367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:01:35.346226Z","time spent":"996.134389ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T19:02:51.332098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.943423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:02:51.332217Z","caller":"traceutil/trace.go:171","msg":"trace[1435825610] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:935; }","duration":"106.121654ms","start":"2026-01-29T19:02:51.226077Z","end":"2026-01-29T19:02:51.332198Z","steps":["trace[1435825610] 'count revisions from in-memory index tree' (duration: 105.833962ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:03:02.556603Z","caller":"traceutil/trace.go:171","msg":"trace[1451589229] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"204.924117ms","start":"2026-01-29T19:03:02.351654Z","end":"2026-01-29T19:03:02.556579Z","steps":["trace[1451589229] 'process raft request' (duration: 204.598315ms)"],"step_count":1}