{"level":"warn","ts":"2026-03-27T01:50:44.989695Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-27T01:50:44.989796Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36: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.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36: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-03-27T01:50:44.989887Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-27T01:50:44.989902Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-03-27T01:50:44.989954Z","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-03-27T01:50:44.990672Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-03-27T01:50:44.994665Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36: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-03-27T01:50:45.003Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.084298ms"} {"level":"info","ts":"2026-03-27T01:50:45.010929Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-03-27T01:50:45.010994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-03-27T01:50:45.01102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-03-27T01:50:45.011036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-27T01:50:45.011046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-03-27T01:50:45.011084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-03-27T01:50:45.018207Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-27T01:50:45.019266Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-27T01:50:45.020209Z","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-03-27T01:50:45.023469Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-27T01:50:45.02427Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-27T01:50:45.027088Z","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-03-27T01:50:45.027303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-03-27T01:50:45.027302Z","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-03-27T01:50:45.027342Z","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-03-27T01:50:45.027401Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-03-27T01:50:45.030172Z","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-03-27T01:50:45.030373Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-27T01:50:45.030401Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-27T01:50:45.030503Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-03-27T01:50:45.030513Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-03-27T01:50:45.512299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-03-27T01:50:45.512373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-27T01:50:45.512407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-03-27T01:50:45.512429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-03-27T01:50:45.512446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-03-27T01:50:45.512463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-03-27T01:50:45.512482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-03-27T01:50:45.559175Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-27T01:50:45.559648Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-27T01:50:45.559629Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-27T01:50:45.56062Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-27T01:50:45.560678Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-27T01:50:45.561542Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-27T01:50:45.564255Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"info","ts":"2026-03-27T01:50:45.563183Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-27T01:50:45.634809Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-03-27T01:50:45.634977Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-27T01:50:45.635035Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-27T01:50:49.177424Z","caller":"traceutil/trace.go:171","msg":"trace[126677675] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"145.563742ms","start":"2026-03-27T01:50:49.031833Z","end":"2026-03-27T01:50:49.177397Z","steps":["trace[126677675] 'process raft request' (duration: 145.402837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.470824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.7331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:50:54.470936Z","caller":"traceutil/trace.go:171","msg":"trace[1928581242] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:355; }","duration":"264.62993ms","start":"2026-03-27T01:50:54.206293Z","end":"2026-03-27T01:50:54.470923Z","steps":["trace[1928581242] 'read index received' (duration: 18.352269ms)","trace[1928581242] 'applied index is now lower than readState.Index' (duration: 246.276781ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:50:54.471Z","caller":"traceutil/trace.go:171","msg":"trace[101133084] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"305.295531ms","start":"2026-03-27T01:50:54.165659Z","end":"2026-03-27T01:50:54.470955Z","steps":["trace[101133084] 'process raft request' (duration: 59.00918ms)","trace[101133084] 'compare' (duration: 245.588073ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:50:54.471361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.389283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-27T01:50:54.471097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.808497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-27T01:50:54.471411Z","caller":"traceutil/trace.go:171","msg":"trace[257588176] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:344; }","duration":"214.473756ms","start":"2026-03-27T01:50:54.256919Z","end":"2026-03-27T01:50:54.471392Z","steps":["trace[257588176] 'agreement among raft nodes before linearized reading' (duration: 214.113452ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:50:54.471438Z","caller":"traceutil/trace.go:171","msg":"trace[1589819403] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:344; }","duration":"265.185661ms","start":"2026-03-27T01:50:54.206231Z","end":"2026-03-27T01:50:54.471416Z","steps":["trace[1589819403] 'agreement among raft nodes before linearized reading' (duration: 264.764104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.47119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.187938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-27T01:50:54.471525Z","caller":"traceutil/trace.go:171","msg":"trace[212763847] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:344; }","duration":"164.569252ms","start":"2026-03-27T01:50:54.306927Z","end":"2026-03-27T01:50:54.471496Z","steps":["trace[212763847] 'agreement among raft nodes before linearized reading' (duration: 164.187247ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.471372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.43925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-03-27T01:50:54.471607Z","caller":"traceutil/trace.go:171","msg":"trace[907668212] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:344; }","duration":"114.773354ms","start":"2026-03-27T01:50:54.356803Z","end":"2026-03-27T01:50:54.471577Z","steps":["trace[907668212] 'agreement among raft nodes before linearized reading' (duration: 114.302375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.471556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.16563Z","time spent":"305.720728ms","remote":"127.0.0.1:55306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-27T01:50:54.841612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.92941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:50:54.841727Z","caller":"traceutil/trace.go:171","msg":"trace[273746521] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"360.70616ms","start":"2026-03-27T01:50:54.481Z","end":"2026-03-27T01:50:54.841707Z","steps":["trace[273746521] 'process raft request' (duration: 152.610634ms)","trace[273746521] 'compare' (duration: 207.347248ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:50:54.841787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.480972Z","time spent":"360.790084ms","remote":"127.0.0.1:54916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-27T01:50:54.847614Z","caller":"traceutil/trace.go:171","msg":"trace[135251007] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"365.524039ms","start":"2026-03-27T01:50:54.482072Z","end":"2026-03-27T01:50:54.847596Z","steps":["trace[135251007] 'process raft request' (duration: 365.359573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.847689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.482063Z","time spent":"365.592912ms","remote":"127.0.0.1:55310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-27T01:50:54.847667Z","caller":"traceutil/trace.go:171","msg":"trace[296598683] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"365.410955ms","start":"2026-03-27T01:50:54.482237Z","end":"2026-03-27T01:50:54.847648Z","steps":["trace[296598683] 'process raft request' (duration: 365.29356ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:50:54.847617Z","caller":"traceutil/trace.go:171","msg":"trace[1239944133] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"366.159814ms","start":"2026-03-27T01:50:54.481442Z","end":"2026-03-27T01:50:54.847602Z","steps":["trace[1239944133] 'process raft request' (duration: 365.914975ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.847828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.482232Z","time spent":"365.511759ms","remote":"127.0.0.1:55034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-27T01:50:54.847951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.481418Z","time spent":"366.444345ms","remote":"127.0.0.1:54990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-27T01:50:54.848171Z","caller":"traceutil/trace.go:171","msg":"trace[1677117878] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"365.606411ms","start":"2026-03-27T01:50:54.482552Z","end":"2026-03-27T01:50:54.848159Z","steps":["trace[1677117878] 'process raft request' (duration: 365.012568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.849271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.482538Z","time spent":"365.713037ms","remote":"127.0.0.1:55158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:50:54.849837Z","caller":"traceutil/trace.go:171","msg":"trace[924065605] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"367.215775ms","start":"2026-03-27T01:50:54.482602Z","end":"2026-03-27T01:50:54.849818Z","steps":["trace[924065605] 'process raft request' (duration: 365.010189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.849926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.482594Z","time spent":"367.304029ms","remote":"127.0.0.1:55158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:50:54.850707Z","caller":"traceutil/trace.go:171","msg":"trace[1947204711] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:356; }","duration":"344.183023ms","start":"2026-03-27T01:50:54.506457Z","end":"2026-03-27T01:50:54.85064Z","steps":["trace[1947204711] 'read index received' (duration: 127.21725ms)","trace[1947204711] 'applied index is now lower than readState.Index' (duration: 216.867369ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:50:54.850803Z","caller":"traceutil/trace.go:171","msg":"trace[1658181900] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"368.163962ms","start":"2026-03-27T01:50:54.482625Z","end":"2026-03-27T01:50:54.850789Z","steps":["trace[1658181900] 'process raft request' (duration: 365.477447ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:50:54.850868Z","caller":"traceutil/trace.go:171","msg":"trace[2102880527] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"368.147431ms","start":"2026-03-27T01:50:54.4827Z","end":"2026-03-27T01:50:54.850847Z","steps":["trace[2102880527] 'process raft request' (duration: 367.681853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.850881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.482602Z","time spent":"368.245795ms","remote":"127.0.0.1:54882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-27T01:50:54.850986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.482668Z","time spent":"368.231635ms","remote":"127.0.0.1:55158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:50:54.851638Z","caller":"traceutil/trace.go:171","msg":"trace[38167382] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"367.497756ms","start":"2026-03-27T01:50:54.484118Z","end":"2026-03-27T01:50:54.851616Z","steps":["trace[38167382] 'process raft request' (duration: 366.319121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.851798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.484109Z","time spent":"367.59743ms","remote":"127.0.0.1:55280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:50:54.853014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.561137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-27T01:50:54.853055Z","caller":"traceutil/trace.go:171","msg":"trace[145880767] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:354; }","duration":"346.654811ms","start":"2026-03-27T01:50:54.50639Z","end":"2026-03-27T01:50:54.853044Z","steps":["trace[145880767] 'agreement among raft nodes before linearized reading' (duration: 345.037188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.85308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.506376Z","time spent":"346.695992ms","remote":"127.0.0.1:55034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-03-27T01:50:54.855241Z","caller":"traceutil/trace.go:171","msg":"trace[1159514266] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"368.720835ms","start":"2026-03-27T01:50:54.482642Z","end":"2026-03-27T01:50:54.851363Z","steps":["trace[1159514266] 'process raft request' (duration: 367.402093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.855561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.482396Z","time spent":"372.922529ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:50:54.936676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.340708ms","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-03-27T01:50:54.936924Z","caller":"traceutil/trace.go:171","msg":"trace[86252834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:355; }","duration":"276.612668ms","start":"2026-03-27T01:50:54.660283Z","end":"2026-03-27T01:50:54.936895Z","steps":["trace[86252834] 'agreement among raft nodes before linearized reading' (duration: 276.296325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.937063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.205592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-27T01:50:54.93715Z","caller":"traceutil/trace.go:171","msg":"trace[779302204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:355; }","duration":"348.357187ms","start":"2026-03-27T01:50:54.588779Z","end":"2026-03-27T01:50:54.937137Z","steps":["trace[779302204] 'agreement among raft nodes before linearized reading' (duration: 348.19054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.937144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.838669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-27T01:50:54.937206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.588755Z","time spent":"348.436359ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-27T01:50:54.937226Z","caller":"traceutil/trace.go:171","msg":"trace[773722077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:355; }","duration":"379.975495ms","start":"2026-03-27T01:50:54.557227Z","end":"2026-03-27T01:50:54.937203Z","steps":["trace[773722077] 'agreement among raft nodes before linearized reading' (duration: 379.798358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:50:54.937276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:50:54.5572Z","time spent":"380.066608ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-27T01:50:55.457733Z","caller":"traceutil/trace.go:171","msg":"trace[763109750] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"216.609793ms","start":"2026-03-27T01:50:55.241095Z","end":"2026-03-27T01:50:55.457705Z","steps":["trace[763109750] 'process raft request' (duration: 216.432107ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:50:57.460284Z","caller":"traceutil/trace.go:171","msg":"trace[665944362] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"146.15418ms","start":"2026-03-27T01:50:57.3141Z","end":"2026-03-27T01:50:57.460254Z","steps":["trace[665944362] 'process raft request' (duration: 145.978182ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:50:57.69453Z","caller":"traceutil/trace.go:171","msg":"trace[943559262] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:409; }","duration":"157.827354ms","start":"2026-03-27T01:50:57.536678Z","end":"2026-03-27T01:50:57.694505Z","steps":["trace[943559262] 'read index received' (duration: 110.391118ms)","trace[943559262] 'applied index is now lower than readState.Index' (duration: 47.435226ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:50:57.694568Z","caller":"traceutil/trace.go:171","msg":"trace[1399444674] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"229.201051ms","start":"2026-03-27T01:50:57.465331Z","end":"2026-03-27T01:50:57.694532Z","steps":["trace[1399444674] 'process raft request' (duration: 181.692623ms)","trace[1399444674] 'compare' (duration: 47.341111ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:50:57.694822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.112705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:50:57.694912Z","caller":"traceutil/trace.go:171","msg":"trace[596265322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:397; }","duration":"158.24054ms","start":"2026-03-27T01:50:57.536653Z","end":"2026-03-27T01:50:57.694893Z","steps":["trace[596265322] 'agreement among raft nodes before linearized reading' (duration: 157.98564ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:50:58.806743Z","caller":"traceutil/trace.go:171","msg":"trace[958344134] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"176.531272ms","start":"2026-03-27T01:50:58.630187Z","end":"2026-03-27T01:50:58.806718Z","steps":["trace[958344134] 'read index received' (duration: 131.964632ms)","trace[958344134] 'applied index is now lower than readState.Index' (duration: 44.56563ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:50:58.806881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.675677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:50:58.806919Z","caller":"traceutil/trace.go:171","msg":"trace[367208859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"176.746019ms","start":"2026-03-27T01:50:58.630163Z","end":"2026-03-27T01:50:58.806909Z","steps":["trace[367208859] 'agreement among raft nodes before linearized reading' (duration: 176.642375ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:50:58.806996Z","caller":"traceutil/trace.go:171","msg":"trace[943406284] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"205.809181ms","start":"2026-03-27T01:50:58.60116Z","end":"2026-03-27T01:50:58.80697Z","steps":["trace[943406284] 'process raft request' (duration: 161.048104ms)","trace[943406284] 'compare' (duration: 44.340041ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:51:02.562613Z","caller":"traceutil/trace.go:171","msg":"trace[552828760] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"134.17754ms","start":"2026-03-27T01:51:02.42838Z","end":"2026-03-27T01:51:02.562557Z","steps":["trace[552828760] 'process raft request' (duration: 82.178284ms)","trace[552828760] 'compare' (duration: 51.900942ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:03.207408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.359706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:51:03.207487Z","caller":"traceutil/trace.go:171","msg":"trace[1411744455] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"208.170339ms","start":"2026-03-27T01:51:02.999304Z","end":"2026-03-27T01:51:03.207474Z","steps":["trace[1411744455] 'read index received' (duration: 45.132µs)","trace[1411744455] 'applied index is now lower than readState.Index' (duration: 208.124357ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:03.207838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.528485ms","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-03-27T01:51:03.207876Z","caller":"traceutil/trace.go:171","msg":"trace[1400001374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"152.825077ms","start":"2026-03-27T01:51:03.055041Z","end":"2026-03-27T01:51:03.207866Z","steps":["trace[1400001374] 'agreement among raft nodes before linearized reading' (duration: 152.533806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:03.207875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.702462ms","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-03-27T01:51:03.208037Z","caller":"traceutil/trace.go:171","msg":"trace[2015958270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"152.866028ms","start":"2026-03-27T01:51:03.055096Z","end":"2026-03-27T01:51:03.207962Z","steps":["trace[2015958270] 'agreement among raft nodes before linearized reading' (duration: 152.590227ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:03.207543Z","caller":"traceutil/trace.go:171","msg":"trace[1292594601] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"633.762729ms","start":"2026-03-27T01:51:02.573749Z","end":"2026-03-27T01:51:03.207512Z","steps":["trace[1292594601] 'process raft request' (duration: 110.233996ms)","trace[1292594601] 'compare' (duration: 523.250412ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:03.208205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.899597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-27T01:51:03.208224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:02.573732Z","time spent":"634.459697ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:51:03.208267Z","caller":"traceutil/trace.go:171","msg":"trace[636938192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"208.998672ms","start":"2026-03-27T01:51:02.999252Z","end":"2026-03-27T01:51:03.208251Z","steps":["trace[636938192] 'agreement among raft nodes before linearized reading' (duration: 208.288313ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:03.462788Z","caller":"traceutil/trace.go:171","msg":"trace[1150962332] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"250.778861ms","start":"2026-03-27T01:51:03.211981Z","end":"2026-03-27T01:51:03.46276Z","steps":["trace[1150962332] 'process raft request' (duration: 173.080805ms)","trace[1150962332] 'compare' (duration: 77.573421ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:05.521565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.826569425s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:51:05.521823Z","caller":"traceutil/trace.go:171","msg":"trace[814838310] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"2.30938027s","start":"2026-03-27T01:51:03.212408Z","end":"2026-03-27T01:51:05.521789Z","steps":["trace[814838310] 'process raft request' (duration: 482.492303ms)","trace[814838310] 'compare' (duration: 1.826453409s)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:51:05.521951Z","caller":"traceutil/trace.go:171","msg":"trace[1090758189] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"2.309474414s","start":"2026-03-27T01:51:03.212441Z","end":"2026-03-27T01:51:05.521916Z","steps":["trace[1090758189] 'process raft request' (duration: 2.309227384s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:05.521978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:03.212396Z","time spent":"2.309502846s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:51:05.522114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:03.212416Z","time spent":"2.30961107s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:51:05.630268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000398268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-27T01:51:05.630334Z","caller":"traceutil/trace.go:171","msg":"trace[989487355] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000506303s","start":"2026-03-27T01:51:03.629811Z","end":"2026-03-27T01:51:05.630318Z","steps":["trace[989487355] 'agreement among raft nodes before linearized reading' (duration: 2.00043112s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:05.630379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:03.629792Z","time spent":"2.000576726s","remote":"127.0.0.1:54808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-27T01:51:06.454486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.738075ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-27T01:51:06.454631Z","caller":"traceutil/trace.go:171","msg":"trace[676520897] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:430; }","duration":"2.824703352s","start":"2026-03-27T01:51:03.629865Z","end":"2026-03-27T01:51:06.454568Z","steps":["trace[676520897] 'read index received' (duration: 65.051199ms)","trace[676520897] 'applied index is now lower than readState.Index' (duration: 2.759650583s)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:06.454795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.718282932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-27T01:51:06.45483Z","caller":"traceutil/trace.go:171","msg":"trace[174403654] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"2.718361686s","start":"2026-03-27T01:51:03.736457Z","end":"2026-03-27T01:51:06.454818Z","steps":["trace[174403654] 'agreement among raft nodes before linearized reading' (duration: 2.718275473s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:06.454894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:03.736436Z","time spent":"2.71844858s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-27T01:51:06.454895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.9517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-27T01:51:06.454934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.198632ms","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-03-27T01:51:06.454958Z","caller":"traceutil/trace.go:171","msg":"trace[1428790796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"824.061204ms","start":"2026-03-27T01:51:05.63088Z","end":"2026-03-27T01:51:06.454941Z","steps":["trace[1428790796] 'agreement among raft nodes before linearized reading' (duration: 823.95911ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:06.454977Z","caller":"traceutil/trace.go:171","msg":"trace[668901719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"988.279915ms","start":"2026-03-27T01:51:05.466685Z","end":"2026-03-27T01:51:06.454965Z","steps":["trace[668901719] 'agreement among raft nodes before linearized reading' (duration: 988.15571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:06.454994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:05.630859Z","time spent":"824.128577ms","remote":"127.0.0.1:54794","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-03-27T01:51:06.455011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:05.46666Z","time spent":"988.340997ms","remote":"127.0.0.1:55086","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":"info","ts":"2026-03-27T01:51:07.009918Z","caller":"traceutil/trace.go:171","msg":"trace[2024962222] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"534.048553ms","start":"2026-03-27T01:51:06.475846Z","end":"2026-03-27T01:51:07.009894Z","steps":["trace[2024962222] 'process raft request' (duration: 533.903546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:07.010044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:06.475829Z","time spent":"534.155938ms","remote":"127.0.0.1:54882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-27T01:51:07.565253Z","caller":"traceutil/trace.go:171","msg":"trace[493561619] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"1.089191629s","start":"2026-03-27T01:51:06.476031Z","end":"2026-03-27T01:51:07.565223Z","steps":["trace[493561619] 'process raft request' (duration: 1.088705259s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:07.565406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:06.476013Z","time spent":"1.089313665s","remote":"127.0.0.1:55086","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-03-27T01:51:07.565709Z","caller":"traceutil/trace.go:171","msg":"trace[1099785617] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"1.089709491s","start":"2026-03-27T01:51:06.475967Z","end":"2026-03-27T01:51:07.565677Z","steps":["trace[1099785617] 'process raft request' (duration: 1.039107256s)","trace[1099785617] 'compare' (duration: 49.565122ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:07.565882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:06.47595Z","time spent":"1.089840266s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:51:07.953332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.270402ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:51:07.953469Z","caller":"traceutil/trace.go:171","msg":"trace[1000915028] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:421; }","duration":"114.421958ms","start":"2026-03-27T01:51:07.839026Z","end":"2026-03-27T01:51:07.953448Z","steps":["trace[1000915028] 'range keys from in-memory index tree' (duration: 114.25594ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:07.954022Z","caller":"traceutil/trace.go:171","msg":"trace[392080324] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"385.501155ms","start":"2026-03-27T01:51:07.568504Z","end":"2026-03-27T01:51:07.954005Z","steps":["trace[392080324] 'process raft request' (duration: 356.191526ms)","trace[392080324] 'compare' (duration: 28.635091ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:07.954249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:07.568483Z","time spent":"385.59086ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:51:07.954278Z","caller":"traceutil/trace.go:171","msg":"trace[262302517] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"384.130009ms","start":"2026-03-27T01:51:07.570115Z","end":"2026-03-27T01:51:07.954245Z","steps":["trace[262302517] 'process raft request' (duration: 383.837627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:07.954449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:07.570074Z","time spent":"384.280575ms","remote":"127.0.0.1:55086","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-03-27T01:51:07.954862Z","caller":"traceutil/trace.go:171","msg":"trace[212387577] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"240.471487ms","start":"2026-03-27T01:51:07.714375Z","end":"2026-03-27T01:51:07.954847Z","steps":["trace[212387577] 'process raft request' (duration: 240.267899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:07.954998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.629867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:51:07.955073Z","caller":"traceutil/trace.go:171","msg":"trace[50944608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:424; }","duration":"325.769753ms","start":"2026-03-27T01:51:07.62929Z","end":"2026-03-27T01:51:07.95506Z","steps":["trace[50944608] 'agreement among raft nodes before linearized reading' (duration: 325.666949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:07.955137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:07.62927Z","time spent":"325.858097ms","remote":"127.0.0.1:54808","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-03-27T01:51:07.954732Z","caller":"traceutil/trace.go:171","msg":"trace[517860745] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:436; }","duration":"325.343276ms","start":"2026-03-27T01:51:07.629373Z","end":"2026-03-27T01:51:07.954716Z","steps":["trace[517860745] 'read index received' (duration: 295.396381ms)","trace[517860745] 'applied index is now lower than readState.Index' (duration: 29.943765ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:24.260392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.647517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:51:24.260478Z","caller":"traceutil/trace.go:171","msg":"trace[667556706] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:525; }","duration":"154.787582ms","start":"2026-03-27T01:51:24.10567Z","end":"2026-03-27T01:51:24.260458Z","steps":["trace[667556706] 'range keys from in-memory index tree' (duration: 154.567873ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:24.665017Z","caller":"traceutil/trace.go:171","msg":"trace[1406171751] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"123.740873ms","start":"2026-03-27T01:51:24.54125Z","end":"2026-03-27T01:51:24.664991Z","steps":["trace[1406171751] 'process raft request' (duration: 123.684831ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:24.665018Z","caller":"traceutil/trace.go:171","msg":"trace[1809330396] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"130.737383ms","start":"2026-03-27T01:51:24.534256Z","end":"2026-03-27T01:51:24.664993Z","steps":["trace[1809330396] 'process raft request' (duration: 130.46806ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:24.685536Z","caller":"traceutil/trace.go:171","msg":"trace[1821875061] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"132.949671ms","start":"2026-03-27T01:51:24.552554Z","end":"2026-03-27T01:51:24.685504Z","steps":["trace[1821875061] 'process raft request' (duration: 132.748182ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:24.685744Z","caller":"traceutil/trace.go:171","msg":"trace[397080150] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:565; }","duration":"132.95016ms","start":"2026-03-27T01:51:24.552778Z","end":"2026-03-27T01:51:24.685728Z","steps":["trace[397080150] 'read index received' (duration: 112.032928ms)","trace[397080150] 'applied index is now lower than readState.Index' (duration: 20.916612ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:51:24.68581Z","caller":"traceutil/trace.go:171","msg":"trace[1412698864] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"132.906948ms","start":"2026-03-27T01:51:24.552899Z","end":"2026-03-27T01:51:24.685806Z","steps":["trace[1412698864] 'process raft request' (duration: 132.521901ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:24.686003Z","caller":"traceutil/trace.go:171","msg":"trace[1548993421] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"129.181384ms","start":"2026-03-27T01:51:24.556817Z","end":"2026-03-27T01:51:24.685998Z","steps":["trace[1548993421] 'process raft request' (duration: 128.626959ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:24.686069Z","caller":"traceutil/trace.go:171","msg":"trace[1723664325] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"120.890258ms","start":"2026-03-27T01:51:24.565172Z","end":"2026-03-27T01:51:24.686062Z","steps":["trace[1723664325] 'process raft request' (duration: 120.310033ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:24.686109Z","caller":"traceutil/trace.go:171","msg":"trace[1015015110] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"119.362472ms","start":"2026-03-27T01:51:24.566742Z","end":"2026-03-27T01:51:24.686105Z","steps":["trace[1015015110] 'process raft request' (duration: 118.784556ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:24.686143Z","caller":"traceutil/trace.go:171","msg":"trace[643740597] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"116.899663ms","start":"2026-03-27T01:51:24.56924Z","end":"2026-03-27T01:51:24.686139Z","steps":["trace[643740597] 'process raft request' (duration: 116.332808ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:24.686273Z","caller":"traceutil/trace.go:171","msg":"trace[1023786839] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"110.844235ms","start":"2026-03-27T01:51:24.575416Z","end":"2026-03-27T01:51:24.68626Z","steps":["trace[1023786839] 'process raft request' (duration: 110.206827ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:24.686356Z","caller":"traceutil/trace.go:171","msg":"trace[337649995] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"110.053311ms","start":"2026-03-27T01:51:24.576298Z","end":"2026-03-27T01:51:24.686351Z","steps":["trace[337649995] 'process raft request' (duration: 109.382042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:24.68635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.547667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:1019"} {"level":"info","ts":"2026-03-27T01:51:24.686419Z","caller":"traceutil/trace.go:171","msg":"trace[1952693866] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:552; }","duration":"133.645361ms","start":"2026-03-27T01:51:24.552754Z","end":"2026-03-27T01:51:24.686399Z","steps":["trace[1952693866] 'agreement among raft nodes before linearized reading' (duration: 133.422511ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:25.825388Z","caller":"traceutil/trace.go:171","msg":"trace[293422935] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.011374754s","start":"2026-03-27T01:51:24.813976Z","end":"2026-03-27T01:51:25.825351Z","steps":["trace[293422935] 'process raft request' (duration: 999.618365ms)","trace[293422935] 'compare' (duration: 11.580011ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:25.825641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:24.81395Z","time spent":"1.011554422s","remote":"127.0.0.1:54856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:51:25.826096Z","caller":"traceutil/trace.go:171","msg":"trace[1977262468] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.007380279s","start":"2026-03-27T01:51:24.8187Z","end":"2026-03-27T01:51:25.82608Z","steps":["trace[1977262468] 'process raft request' (duration: 1.007011922s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:25.826219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:24.818681Z","time spent":"1.007463531s","remote":"127.0.0.1:54856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:51:25.826276Z","caller":"traceutil/trace.go:171","msg":"trace[570759208] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.003972628s","start":"2026-03-27T01:51:24.822274Z","end":"2026-03-27T01:51:25.826247Z","steps":["trace[570759208] 'process raft request' (duration: 1.003626783s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:25.826452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:24.822252Z","time spent":"1.004103693s","remote":"127.0.0.1:54856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:51:25.826805Z","caller":"traceutil/trace.go:171","msg":"trace[6373018] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:590; }","duration":"173.334302ms","start":"2026-03-27T01:51:25.653457Z","end":"2026-03-27T01:51:25.826791Z","steps":["trace[6373018] 'read index received' (duration: 160.16464ms)","trace[6373018] 'applied index is now lower than readState.Index' (duration: 13.168652ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:51:25.82684Z","caller":"traceutil/trace.go:171","msg":"trace[1956056364] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"942.941847ms","start":"2026-03-27T01:51:24.883868Z","end":"2026-03-27T01:51:25.82681Z","steps":["trace[1956056364] 'process raft request' (duration: 942.260858ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:25.826941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.482307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-27T01:51:25.82698Z","caller":"traceutil/trace.go:171","msg":"trace[1181038514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"173.559083ms","start":"2026-03-27T01:51:25.653411Z","end":"2026-03-27T01:51:25.82697Z","steps":["trace[1181038514] 'agreement among raft nodes before linearized reading' (duration: 173.460738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:25.826988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:24.883844Z","time spent":"943.066973ms","remote":"127.0.0.1:54856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-27T01:51:25.827153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.738856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-27T01:51:25.827765Z","caller":"traceutil/trace.go:171","msg":"trace[1373486706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"142.045169ms","start":"2026-03-27T01:51:25.68536Z","end":"2026-03-27T01:51:25.827405Z","steps":["trace[1373486706] 'agreement among raft nodes before linearized reading' (duration: 141.696724ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:28.681022Z","caller":"traceutil/trace.go:171","msg":"trace[1735071471] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"139.212575ms","start":"2026-03-27T01:51:28.541769Z","end":"2026-03-27T01:51:28.680982Z","steps":["trace[1735071471] 'read index received' (duration: 136.067115ms)","trace[1735071471] 'applied index is now lower than readState.Index' (duration: 3.14423ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:51:28.681104Z","caller":"traceutil/trace.go:171","msg":"trace[933983534] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"144.927141ms","start":"2026-03-27T01:51:28.536131Z","end":"2026-03-27T01:51:28.681058Z","steps":["trace[933983534] 'process raft request' (duration: 142.389087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:28.681268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.490728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-03-27T01:51:28.681329Z","caller":"traceutil/trace.go:171","msg":"trace[641296456] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:614; }","duration":"139.614444ms","start":"2026-03-27T01:51:28.541699Z","end":"2026-03-27T01:51:28.681314Z","steps":["trace[641296456] 'agreement among raft nodes before linearized reading' (duration: 139.454157ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:31.803566Z","caller":"traceutil/trace.go:171","msg":"trace[1021704262] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"128.030052ms","start":"2026-03-27T01:51:31.675511Z","end":"2026-03-27T01:51:31.803541Z","steps":["trace[1021704262] 'process raft request' (duration: 127.903666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:32.177717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.659702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-27T01:51:32.177787Z","caller":"traceutil/trace.go:171","msg":"trace[399358734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"313.774406ms","start":"2026-03-27T01:51:31.863992Z","end":"2026-03-27T01:51:32.177766Z","steps":["trace[399358734] 'range keys from in-memory index tree' (duration: 313.558797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:32.177823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:31.863976Z","time spent":"313.84025ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-27T01:51:32.177811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.293835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-27T01:51:32.177869Z","caller":"traceutil/trace.go:171","msg":"trace[1921689550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"315.389481ms","start":"2026-03-27T01:51:31.862465Z","end":"2026-03-27T01:51:32.177854Z","steps":["trace[1921689550] 'range keys from in-memory index tree' (duration: 315.206752ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:32.177892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:31.862447Z","time spent":"315.439842ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-27T01:51:32.767474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583484102678599,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-27T01:51:32.96519Z","caller":"traceutil/trace.go:171","msg":"trace[173077227] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"783.649669ms","start":"2026-03-27T01:51:32.181508Z","end":"2026-03-27T01:51:32.965157Z","steps":["trace[173077227] 'process raft request' (duration: 754.431603ms)","trace[173077227] 'compare' (duration: 28.927764ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:32.965304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:32.181499Z","time spent":"783.748644ms","remote":"127.0.0.1:55086","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-03-27T01:51:32.965369Z","caller":"traceutil/trace.go:171","msg":"trace[1923360301] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"783.695892ms","start":"2026-03-27T01:51:32.181642Z","end":"2026-03-27T01:51:32.965338Z","steps":["trace[1923360301] 'process raft request' (duration: 783.449281ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:32.965588Z","caller":"traceutil/trace.go:171","msg":"trace[1282394930] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"783.883419ms","start":"2026-03-27T01:51:32.181694Z","end":"2026-03-27T01:51:32.965578Z","steps":["trace[1282394930] 'process raft request' (duration: 783.563605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:32.965575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:32.181636Z","time spent":"783.803626ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:51:32.96565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:32.181686Z","time spent":"783.929722ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:51:32.965984Z","caller":"traceutil/trace.go:171","msg":"trace[7850298] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:661; }","duration":"698.837789ms","start":"2026-03-27T01:51:32.267122Z","end":"2026-03-27T01:51:32.965959Z","steps":["trace[7850298] 'read index received' (duration: 668.810506ms)","trace[7850298] 'applied index is now lower than readState.Index' (duration: 30.026223ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:32.966128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.819097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:51:32.96612Z","caller":"traceutil/trace.go:171","msg":"trace[1541246676] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"758.113629ms","start":"2026-03-27T01:51:32.207986Z","end":"2026-03-27T01:51:32.9661Z","steps":["trace[1541246676] 'process raft request' (duration: 757.525213ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:32.966173Z","caller":"traceutil/trace.go:171","msg":"trace[632604780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"335.911291ms","start":"2026-03-27T01:51:32.630249Z","end":"2026-03-27T01:51:32.96616Z","steps":["trace[632604780] 'agreement among raft nodes before linearized reading' (duration: 335.837728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:32.966187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.063049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x7ndp\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-03-27T01:51:32.966205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:32.630227Z","time spent":"335.970114ms","remote":"127.0.0.1:54808","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-03-27T01:51:32.966239Z","caller":"traceutil/trace.go:171","msg":"trace[1144331771] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x7ndp; range_end:; response_count:1; response_revision:639; }","duration":"699.127342ms","start":"2026-03-27T01:51:32.267082Z","end":"2026-03-27T01:51:32.96621Z","steps":["trace[1144331771] 'agreement among raft nodes before linearized reading' (duration: 698.994636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:32.966262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:32.267063Z","time spent":"699.192806ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-x7ndp\" "} {"level":"warn","ts":"2026-03-27T01:51:32.966255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:32.207961Z","time spent":"758.218394ms","remote":"127.0.0.1:54904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:51:33.421522Z","caller":"traceutil/trace.go:171","msg":"trace[1695866166] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"443.436631ms","start":"2026-03-27T01:51:32.978049Z","end":"2026-03-27T01:51:33.421486Z","steps":["trace[1695866166] 'process raft request' (duration: 377.117477ms)","trace[1695866166] 'compare' (duration: 66.155638ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:33.421709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:32.978029Z","time spent":"443.578468ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:51:34.400866Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.045774209s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-27T01:51:35.610998Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.209930987s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-27T01:51:36.593481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.192292217s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:51:36.593724Z","caller":"traceutil/trace.go:171","msg":"trace[794859797] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"3.560640122s","start":"2026-03-27T01:51:33.033037Z","end":"2026-03-27T01:51:36.593677Z","steps":["trace[794859797] 'process raft request' (duration: 1.368088103s)","trace[794859797] 'compare' (duration: 2.192036906s)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:36.593837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:33.032989Z","time spent":"3.56079893s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-27T01:51:36.612708Z","caller":"traceutil/trace.go:171","msg":"trace[1728595414] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:666; }","duration":"2.38730244s","start":"2026-03-27T01:51:34.22538Z","end":"2026-03-27T01:51:36.612682Z","steps":["trace[1728595414] 'read index received' (duration: 175.728456ms)","trace[1728595414] 'applied index is now lower than readState.Index' (duration: 2.211572404s)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:51:36.612988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.98350443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:51:36.613034Z","caller":"traceutil/trace.go:171","msg":"trace[1991600922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.983568973s","start":"2026-03-27T01:51:34.629454Z","end":"2026-03-27T01:51:36.613023Z","steps":["trace[1991600922] 'agreement among raft nodes before linearized reading' (duration: 1.983386135s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:36.613064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.67532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-27T01:51:36.613124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643186155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-27T01:51:36.613147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643187445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-27T01:51:36.61318Z","caller":"traceutil/trace.go:171","msg":"trace[1419919935] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"1.643282518s","start":"2026-03-27T01:51:34.969884Z","end":"2026-03-27T01:51:36.613167Z","steps":["trace[1419919935] 'agreement among raft nodes before linearized reading' (duration: 1.643117181s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:36.613149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:34.629397Z","time spent":"1.983738181s","remote":"127.0.0.1:54808","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-03-27T01:51:36.613188Z","caller":"traceutil/trace.go:171","msg":"trace[350061632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"195.857048ms","start":"2026-03-27T01:51:36.417304Z","end":"2026-03-27T01:51:36.613161Z","steps":["trace[350061632] 'agreement among raft nodes before linearized reading' (duration: 195.631418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:36.613215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:34.969849Z","time spent":"1.643358252s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-27T01:51:36.613221Z","caller":"traceutil/trace.go:171","msg":"trace[1924455019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"1.64329072s","start":"2026-03-27T01:51:34.969911Z","end":"2026-03-27T01:51:36.613202Z","steps":["trace[1924455019] 'agreement among raft nodes before linearized reading' (duration: 1.64308892s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:36.613247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643376683s","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-03-27T01:51:36.613262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:34.969904Z","time spent":"1.643348662s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-27T01:51:36.613305Z","caller":"traceutil/trace.go:171","msg":"trace[1993203088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"1.643470067s","start":"2026-03-27T01:51:34.969818Z","end":"2026-03-27T01:51:36.613289Z","steps":["trace[1993203088] 'agreement among raft nodes before linearized reading' (duration: 1.64330013s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:36.613355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:34.969798Z","time spent":"1.643544901s","remote":"127.0.0.1:55086","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-03-27T01:51:36.613642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.387682346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-27T01:51:36.613693Z","caller":"traceutil/trace.go:171","msg":"trace[1551684460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"2.388354248s","start":"2026-03-27T01:51:34.225325Z","end":"2026-03-27T01:51:36.61368Z","steps":["trace[1551684460] 'agreement among raft nodes before linearized reading' (duration: 2.387501579s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:36.613729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:34.225298Z","time spent":"2.38841908s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-27T01:51:38.720619Z","caller":"traceutil/trace.go:171","msg":"trace[32187764] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"227.735966ms","start":"2026-03-27T01:51:38.492858Z","end":"2026-03-27T01:51:38.720594Z","steps":["trace[32187764] 'read index received' (duration: 227.541138ms)","trace[32187764] 'applied index is now lower than readState.Index' (duration: 193.378µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:51:38.720788Z","caller":"traceutil/trace.go:171","msg":"trace[1859373907] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"277.531142ms","start":"2026-03-27T01:51:38.443224Z","end":"2026-03-27T01:51:38.720756Z","steps":["trace[1859373907] 'process raft request' (duration: 277.179056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:38.720853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.978188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-27T01:51:38.720901Z","caller":"traceutil/trace.go:171","msg":"trace[1101587729] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:678; }","duration":"228.086562ms","start":"2026-03-27T01:51:38.492803Z","end":"2026-03-27T01:51:38.720889Z","steps":["trace[1101587729] 'agreement among raft nodes before linearized reading' (duration: 227.957717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:38.727674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.162346ms","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-03-27T01:51:38.727739Z","caller":"traceutil/trace.go:171","msg":"trace[32177665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"105.316372ms","start":"2026-03-27T01:51:38.622407Z","end":"2026-03-27T01:51:38.727724Z","steps":["trace[32177665] 'agreement among raft nodes before linearized reading' (duration: 105.127013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:38.727765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.990746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-27T01:51:38.727983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.257488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-27T01:51:38.728514Z","caller":"traceutil/trace.go:171","msg":"trace[592614717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"103.811964ms","start":"2026-03-27T01:51:38.624682Z","end":"2026-03-27T01:51:38.728494Z","steps":["trace[592614717] 'agreement among raft nodes before linearized reading' (duration: 103.236397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:38.728006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.856485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-27T01:51:38.728656Z","caller":"traceutil/trace.go:171","msg":"trace[569712181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"104.527205ms","start":"2026-03-27T01:51:38.624112Z","end":"2026-03-27T01:51:38.728639Z","steps":["trace[569712181] 'agreement among raft nodes before linearized reading' (duration: 103.787951ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:38.72809Z","caller":"traceutil/trace.go:171","msg":"trace[1554283007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:680; }","duration":"103.322142ms","start":"2026-03-27T01:51:38.624738Z","end":"2026-03-27T01:51:38.72806Z","steps":["trace[1554283007] 'agreement among raft nodes before linearized reading' (duration: 102.902223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:52.946944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583484102678921,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-27T01:51:53.447754Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583484102678921,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-27T01:51:53.548124Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.441025859s","expected-duration":"1s"} {"level":"info","ts":"2026-03-27T01:51:53.548597Z","caller":"traceutil/trace.go:171","msg":"trace[1268964360] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"1.441704671s","start":"2026-03-27T01:51:52.106869Z","end":"2026-03-27T01:51:53.548574Z","steps":["trace[1268964360] 'process raft request' (duration: 1.441538823s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:53.549219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:52.106815Z","time spent":"1.441843887s","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:51:53.554846Z","caller":"traceutil/trace.go:171","msg":"trace[2089436493] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:836; }","duration":"1.108490413s","start":"2026-03-27T01:51:52.446326Z","end":"2026-03-27T01:51:53.554816Z","steps":["trace[2089436493] 'read index received' (duration: 1.102052609s)","trace[2089436493] 'applied index is now lower than readState.Index' (duration: 6.436834ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:51:53.554955Z","caller":"traceutil/trace.go:171","msg":"trace[1907767755] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"1.169425887s","start":"2026-03-27T01:51:52.385454Z","end":"2026-03-27T01:51:53.55488Z","steps":["trace[1907767755] 'process raft request' (duration: 1.169240258s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:53.55504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10864423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-27T01:51:53.55505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.699096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:51:53.555082Z","caller":"traceutil/trace.go:171","msg":"trace[1153069182] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:806; }","duration":"1.108789686s","start":"2026-03-27T01:51:52.44628Z","end":"2026-03-27T01:51:53.555069Z","steps":["trace[1153069182] 'agreement among raft nodes before linearized reading' (duration: 1.10866075s)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:53.555121Z","caller":"traceutil/trace.go:171","msg":"trace[792565786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"924.812022ms","start":"2026-03-27T01:51:52.630272Z","end":"2026-03-27T01:51:53.555084Z","steps":["trace[792565786] 'agreement among raft nodes before linearized reading' (duration: 924.712988ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:53.555153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:52.446232Z","time spent":"1.108903983s","remote":"127.0.0.1:55310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-03-27T01:51:53.555166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:52.630252Z","time spent":"924.901285ms","remote":"127.0.0.1:54808","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-03-27T01:51:53.555332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:52.385425Z","time spent":"1.169607975s","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:51:53.555419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.15765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-27T01:51:53.555452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.776798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-27T01:51:53.555367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.587222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-27T01:51:53.555508Z","caller":"traceutil/trace.go:171","msg":"trace[995572941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:806; }","duration":"719.877211ms","start":"2026-03-27T01:51:52.835618Z","end":"2026-03-27T01:51:53.555496Z","steps":["trace[995572941] 'agreement among raft nodes before linearized reading' (duration: 719.765866ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:53.555507Z","caller":"traceutil/trace.go:171","msg":"trace[1072043845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:806; }","duration":"705.283007ms","start":"2026-03-27T01:51:52.850203Z","end":"2026-03-27T01:51:53.555486Z","steps":["trace[1072043845] 'agreement among raft nodes before linearized reading' (duration: 705.017685ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:51:53.55553Z","caller":"traceutil/trace.go:171","msg":"trace[2120825309] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:806; }","duration":"234.802372ms","start":"2026-03-27T01:51:53.320704Z","end":"2026-03-27T01:51:53.555507Z","steps":["trace[2120825309] 'agreement among raft nodes before linearized reading' (duration: 234.459716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:53.555556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:52.835593Z","time spent":"719.949215ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-27T01:51:53.555562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:52.850193Z","time spent":"705.360181ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-27T01:51:53.555345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.617429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-27T01:51:53.555679Z","caller":"traceutil/trace.go:171","msg":"trace[1315344388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:806; }","duration":"709.922204ms","start":"2026-03-27T01:51:52.845719Z","end":"2026-03-27T01:51:53.555642Z","steps":["trace[1315344388] 'agreement among raft nodes before linearized reading' (duration: 709.529115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:53.556611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:52.845654Z","time spent":"710.937942ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-27T01:51:53.560841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.813114ms","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-03-27T01:51:53.561003Z","caller":"traceutil/trace.go:171","msg":"trace[2038073892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"714.995443ms","start":"2026-03-27T01:51:52.845994Z","end":"2026-03-27T01:51:53.560989Z","steps":["trace[2038073892] 'agreement among raft nodes before linearized reading' (duration: 709.138428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:51:53.561137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:51:52.845982Z","time spent":"715.13239ms","remote":"127.0.0.1:55086","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-03-27T01:52:01.45559Z","caller":"traceutil/trace.go:171","msg":"trace[1764463892] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"255.694542ms","start":"2026-03-27T01:52:01.199869Z","end":"2026-03-27T01:52:01.455563Z","steps":["trace[1764463892] 'process raft request' (duration: 255.542065ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:01.803378Z","caller":"traceutil/trace.go:171","msg":"trace[601256255] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"170.230397ms","start":"2026-03-27T01:52:01.633127Z","end":"2026-03-27T01:52:01.803358Z","steps":["trace[601256255] 'process raft request' (duration: 169.708692ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:01.803472Z","caller":"traceutil/trace.go:171","msg":"trace[1181001883] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"169.108644ms","start":"2026-03-27T01:52:01.634321Z","end":"2026-03-27T01:52:01.80343Z","steps":["trace[1181001883] 'process raft request' (duration: 168.712324ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:01.803828Z","caller":"traceutil/trace.go:171","msg":"trace[1794211202] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"170.584055ms","start":"2026-03-27T01:52:01.633214Z","end":"2026-03-27T01:52:01.803798Z","steps":["trace[1794211202] 'process raft request' (duration: 169.742345ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:01.803952Z","caller":"traceutil/trace.go:171","msg":"trace[1104561726] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"169.665352ms","start":"2026-03-27T01:52:01.634259Z","end":"2026-03-27T01:52:01.803925Z","steps":["trace[1104561726] 'process raft request' (duration: 168.744397ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:03.9404Z","caller":"traceutil/trace.go:171","msg":"trace[23117250] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"130.440429ms","start":"2026-03-27T01:52:03.809941Z","end":"2026-03-27T01:52:03.940381Z","steps":["trace[23117250] 'process raft request' (duration: 130.327703ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:03.941672Z","caller":"traceutil/trace.go:171","msg":"trace[208067835] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"130.46492ms","start":"2026-03-27T01:52:03.811189Z","end":"2026-03-27T01:52:03.941654Z","steps":["trace[208067835] 'process raft request' (duration: 130.353045ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:03.942023Z","caller":"traceutil/trace.go:171","msg":"trace[1683128068] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"130.598595ms","start":"2026-03-27T01:52:03.8114Z","end":"2026-03-27T01:52:03.941999Z","steps":["trace[1683128068] 'process raft request' (duration: 130.460719ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:03.942065Z","caller":"traceutil/trace.go:171","msg":"trace[1962261708] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"130.723332ms","start":"2026-03-27T01:52:03.811324Z","end":"2026-03-27T01:52:03.942047Z","steps":["trace[1962261708] 'process raft request' (duration: 130.299162ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:08.590475Z","caller":"traceutil/trace.go:171","msg":"trace[403115422] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"144.5086ms","start":"2026-03-27T01:52:08.445945Z","end":"2026-03-27T01:52:08.590454Z","steps":["trace[403115422] 'process raft request' (duration: 144.381553ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:08.590541Z","caller":"traceutil/trace.go:171","msg":"trace[766698778] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1009; }","duration":"136.810519ms","start":"2026-03-27T01:52:08.453705Z","end":"2026-03-27T01:52:08.590515Z","steps":["trace[766698778] 'read index received' (duration: 136.797478ms)","trace[766698778] 'applied index is now lower than readState.Index' (duration: 10.751µs)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:52:08.590763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.005708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-27T01:52:08.590825Z","caller":"traceutil/trace.go:171","msg":"trace[1739197296] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:974; }","duration":"137.118623ms","start":"2026-03-27T01:52:08.453689Z","end":"2026-03-27T01:52:08.590808Z","steps":["trace[1739197296] 'agreement among raft nodes before linearized reading' (duration: 136.966025ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:08.821858Z","caller":"traceutil/trace.go:171","msg":"trace[628956997] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"231.202705ms","start":"2026-03-27T01:52:08.590624Z","end":"2026-03-27T01:52:08.821826Z","steps":["trace[628956997] 'read index received' (duration: 177.860226ms)","trace[628956997] 'applied index is now lower than readState.Index' (duration: 53.341059ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:52:08.822024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.074185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-27T01:52:08.822044Z","caller":"traceutil/trace.go:171","msg":"trace[608694186] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"315.705045ms","start":"2026-03-27T01:52:08.506309Z","end":"2026-03-27T01:52:08.822014Z","steps":["trace[608694186] 'process raft request' (duration: 262.196778ms)","trace[608694186] 'compare' (duration: 52.987242ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:52:08.822109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.236688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-27T01:52:08.822155Z","caller":"traceutil/trace.go:171","msg":"trace[1020911753] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:975; }","duration":"328.341723ms","start":"2026-03-27T01:52:08.493801Z","end":"2026-03-27T01:52:08.822142Z","steps":["trace[1020911753] 'agreement among raft nodes before linearized reading' (duration: 328.134883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:52:08.822211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:52:08.493787Z","time spent":"328.414967ms","remote":"127.0.0.1:54840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"warn","ts":"2026-03-27T01:52:08.822196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:52:08.506284Z","time spent":"315.831291ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:52:08.822255Z","caller":"traceutil/trace.go:171","msg":"trace[627977676] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:975; }","duration":"260.198751ms","start":"2026-03-27T01:52:08.561883Z","end":"2026-03-27T01:52:08.822082Z","steps":["trace[627977676] 'agreement among raft nodes before linearized reading' (duration: 260.030043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:52:08.823063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.798773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-27T01:52:08.823291Z","caller":"traceutil/trace.go:171","msg":"trace[290950740] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:975; }","duration":"319.689758ms","start":"2026-03-27T01:52:08.50354Z","end":"2026-03-27T01:52:08.823229Z","steps":["trace[290950740] 'agreement among raft nodes before linearized reading' (duration: 318.586155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:52:08.823513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:52:08.50352Z","time spent":"319.823583ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-03-27T01:52:08.883744Z","caller":"traceutil/trace.go:171","msg":"trace[1884792772] transaction","detail":"{read_only:false; number_of_response:1; response_revision:975; }","duration":"292.110579ms","start":"2026-03-27T01:52:08.591613Z","end":"2026-03-27T01:52:08.883724Z","steps":["trace[1884792772] 'process raft request' (duration: 291.996964ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:52:09.065288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.005716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:52:09.065468Z","caller":"traceutil/trace.go:171","msg":"trace[1848255338] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"176.032702ms","start":"2026-03-27T01:52:08.889409Z","end":"2026-03-27T01:52:09.065442Z","steps":["trace[1848255338] 'process raft request' (duration: 67.734712ms)","trace[1848255338] 'compare' (duration: 107.830198ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:52:10.155354Z","caller":"traceutil/trace.go:171","msg":"trace[1757990171] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"179.095297ms","start":"2026-03-27T01:52:09.976222Z","end":"2026-03-27T01:52:10.155318Z","steps":["trace[1757990171] 'process raft request' (duration: 179.035374ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:10.155391Z","caller":"traceutil/trace.go:171","msg":"trace[2036628713] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"181.276082ms","start":"2026-03-27T01:52:09.974081Z","end":"2026-03-27T01:52:10.155357Z","steps":["trace[2036628713] 'process raft request' (duration: 180.996349ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:13.643898Z","caller":"traceutil/trace.go:171","msg":"trace[268459213] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"100.538644ms","start":"2026-03-27T01:52:13.543316Z","end":"2026-03-27T01:52:13.643855Z","steps":["trace[268459213] 'read index received' (duration: 11.12058ms)","trace[268459213] 'applied index is now lower than readState.Index' (duration: 89.415974ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:52:13.644271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.88708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-27T01:52:13.644457Z","caller":"traceutil/trace.go:171","msg":"trace[2029485785] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:997; }","duration":"101.164854ms","start":"2026-03-27T01:52:13.543214Z","end":"2026-03-27T01:52:13.644379Z","steps":["trace[2029485785] 'agreement among raft nodes before linearized reading' (duration: 100.771245ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:22.149913Z","caller":"traceutil/trace.go:171","msg":"trace[2112864190] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"124.498156ms","start":"2026-03-27T01:52:22.025398Z","end":"2026-03-27T01:52:22.149896Z","steps":["trace[2112864190] 'process raft request' (duration: 124.315747ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:22.149897Z","caller":"traceutil/trace.go:171","msg":"trace[111316071] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"104.448725ms","start":"2026-03-27T01:52:22.045423Z","end":"2026-03-27T01:52:22.149872Z","steps":["trace[111316071] 'read index received' (duration: 104.359851ms)","trace[111316071] 'applied index is now lower than readState.Index' (duration: 87.714µs)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:52:22.150048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.615152ms","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-03-27T01:52:22.150086Z","caller":"traceutil/trace.go:171","msg":"trace[1123288439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1025; }","duration":"104.708518ms","start":"2026-03-27T01:52:22.04537Z","end":"2026-03-27T01:52:22.150079Z","steps":["trace[1123288439] 'agreement among raft nodes before linearized reading' (duration: 104.571401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:52:22.17093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.822125ms","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-03-27T01:52:22.171038Z","caller":"traceutil/trace.go:171","msg":"trace[1196128237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"112.976412ms","start":"2026-03-27T01:52:22.058019Z","end":"2026-03-27T01:52:22.170996Z","steps":["trace[1196128237] 'agreement among raft nodes before linearized reading' (duration: 112.656436ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:40.491384Z","caller":"traceutil/trace.go:171","msg":"trace[1864837854] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"230.533366ms","start":"2026-03-27T01:52:40.260816Z","end":"2026-03-27T01:52:40.49135Z","steps":["trace[1864837854] 'process raft request' (duration: 230.352107ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:40.59295Z","caller":"traceutil/trace.go:171","msg":"trace[838142831] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1133; }","duration":"283.825038ms","start":"2026-03-27T01:52:40.309096Z","end":"2026-03-27T01:52:40.592921Z","steps":["trace[838142831] 'read index received' (duration: 182.068923ms)","trace[838142831] 'applied index is now lower than readState.Index' (duration: 101.754835ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:52:40.593238Z","caller":"traceutil/trace.go:171","msg":"trace[1049561508] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"328.678681ms","start":"2026-03-27T01:52:40.264542Z","end":"2026-03-27T01:52:40.593221Z","steps":["trace[1049561508] 'process raft request' (duration: 291.90697ms)","trace[1049561508] 'compare' (duration: 36.373711ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:52:40.593367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:52:40.264529Z","time spent":"328.750043ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:52:40.593512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.368868ms","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-03-27T01:52:40.593573Z","caller":"traceutil/trace.go:171","msg":"trace[492459406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1091; }","duration":"271.457792ms","start":"2026-03-27T01:52:40.322099Z","end":"2026-03-27T01:52:40.593557Z","steps":["trace[492459406] 'agreement among raft nodes before linearized reading' (duration: 271.238472ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:52:40.593833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.738483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-27T01:52:40.593882Z","caller":"traceutil/trace.go:171","msg":"trace[1670997446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1091; }","duration":"284.808357ms","start":"2026-03-27T01:52:40.309062Z","end":"2026-03-27T01:52:40.593871Z","steps":["trace[1670997446] 'agreement among raft nodes before linearized reading' (duration: 284.654699ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:52.899717Z","caller":"traceutil/trace.go:171","msg":"trace[323880126] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1176; }","duration":"248.838633ms","start":"2026-03-27T01:52:52.650848Z","end":"2026-03-27T01:52:52.899687Z","steps":["trace[323880126] 'read index received' (duration: 248.628822ms)","trace[323880126] 'applied index is now lower than readState.Index' (duration: 208.731µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:52:52.899869Z","caller":"traceutil/trace.go:171","msg":"trace[977708722] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"249.197701ms","start":"2026-03-27T01:52:52.650659Z","end":"2026-03-27T01:52:52.899857Z","steps":["trace[977708722] 'process raft request' (duration: 248.852044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:52:52.900231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.38101ms","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-03-27T01:52:52.900308Z","caller":"traceutil/trace.go:171","msg":"trace[1707273846] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1131; }","duration":"249.473084ms","start":"2026-03-27T01:52:52.650819Z","end":"2026-03-27T01:52:52.900292Z","steps":["trace[1707273846] 'agreement among raft nodes before linearized reading' (duration: 249.347828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:52:52.900071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.985847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-27T01:52:52.90046Z","caller":"traceutil/trace.go:171","msg":"trace[1006264130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1131; }","duration":"190.44255ms","start":"2026-03-27T01:52:52.710011Z","end":"2026-03-27T01:52:52.900453Z","steps":["trace[1006264130] 'agreement among raft nodes before linearized reading' (duration: 189.892233ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:52:53.206947Z","caller":"traceutil/trace.go:171","msg":"trace[1843362190] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"302.430308ms","start":"2026-03-27T01:52:52.90447Z","end":"2026-03-27T01:52:53.2069Z","steps":["trace[1843362190] 'process raft request' (duration: 220.923295ms)","trace[1843362190] 'compare' (duration: 81.263721ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:52:53.207153Z","caller":"traceutil/trace.go:171","msg":"trace[137868858] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"143.783742ms","start":"2026-03-27T01:52:53.063341Z","end":"2026-03-27T01:52:53.207125Z","steps":["trace[137868858] 'process raft request' (duration: 143.72573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:52:53.207194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:52:52.904459Z","time spent":"302.614068ms","remote":"127.0.0.1:55086","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-03-27T01:53:01.11697Z","caller":"traceutil/trace.go:171","msg":"trace[1941562585] linearizableReadLoop","detail":"{readStateIndex:1205; appliedIndex:1204; }","duration":"113.587572ms","start":"2026-03-27T01:53:01.003349Z","end":"2026-03-27T01:53:01.116937Z","steps":["trace[1941562585] 'read index received' (duration: 113.299268ms)","trace[1941562585] 'applied index is now lower than readState.Index' (duration: 287.234µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:53:01.117059Z","caller":"traceutil/trace.go:171","msg":"trace[232753761] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"181.371782ms","start":"2026-03-27T01:53:00.935649Z","end":"2026-03-27T01:53:01.117021Z","steps":["trace[232753761] 'process raft request' (duration: 181.100259ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:53:01.117307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.92917ms","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-03-27T01:53:01.117688Z","caller":"traceutil/trace.go:171","msg":"trace[1706365164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1157; }","duration":"114.390933ms","start":"2026-03-27T01:53:01.003283Z","end":"2026-03-27T01:53:01.117674Z","steps":["trace[1706365164] 'agreement among raft nodes before linearized reading' (duration: 113.815644ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:53:07.493643Z","caller":"traceutil/trace.go:171","msg":"trace[1717250323] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"225.636415ms","start":"2026-03-27T01:53:07.267979Z","end":"2026-03-27T01:53:07.493615Z","steps":["trace[1717250323] 'process raft request' (duration: 225.428295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:53:07.493778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.631084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:53:07.493852Z","caller":"traceutil/trace.go:171","msg":"trace[1764200921] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1177; }","duration":"225.786843ms","start":"2026-03-27T01:53:07.268054Z","end":"2026-03-27T01:53:07.493841Z","steps":["trace[1764200921] 'agreement among raft nodes before linearized reading' (duration: 225.645305ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:53:07.493642Z","caller":"traceutil/trace.go:171","msg":"trace[1613012385] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"225.467935ms","start":"2026-03-27T01:53:07.268111Z","end":"2026-03-27T01:53:07.493579Z","steps":["trace[1613012385] 'read index received' (duration: 225.3627ms)","trace[1613012385] 'applied index is now lower than readState.Index' (duration: 104.135µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:53:07.615255Z","caller":"traceutil/trace.go:171","msg":"trace[532800464] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1226; }","duration":"120.978126ms","start":"2026-03-27T01:53:07.494257Z","end":"2026-03-27T01:53:07.615235Z","steps":["trace[532800464] 'read index received' (duration: 29.723855ms)","trace[532800464] 'applied index is now lower than readState.Index' (duration: 91.253401ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:53:07.615468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.484453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-27T01:53:07.615505Z","caller":"traceutil/trace.go:171","msg":"trace[60007510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1178; }","duration":"141.590398ms","start":"2026-03-27T01:53:07.473907Z","end":"2026-03-27T01:53:07.615497Z","steps":["trace[60007510] 'agreement among raft nodes before linearized reading' (duration: 141.41466ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:53:07.615442Z","caller":"traceutil/trace.go:171","msg":"trace[798415721] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"209.624864ms","start":"2026-03-27T01:53:07.405708Z","end":"2026-03-27T01:53:07.615333Z","steps":["trace[798415721] 'process raft request' (duration: 118.277869ms)","trace[798415721] 'compare' (duration: 91.078081ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:53:10.965122Z","caller":"traceutil/trace.go:171","msg":"trace[778034545] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"199.78546ms","start":"2026-03-27T01:53:10.765302Z","end":"2026-03-27T01:53:10.965087Z","steps":["trace[778034545] 'process raft request' (duration: 171.514287ms)","trace[778034545] 'compare' (duration: 28.142897ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:53:22.756024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.297958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:53:22.756136Z","caller":"traceutil/trace.go:171","msg":"trace[229754169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"125.489526ms","start":"2026-03-27T01:53:22.630625Z","end":"2026-03-27T01:53:22.756114Z","steps":["trace[229754169] 'range keys from in-memory index tree' (duration: 125.187132ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:53:23.487448Z","caller":"traceutil/trace.go:171","msg":"trace[1924043517] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"192.071584ms","start":"2026-03-27T01:53:23.295348Z","end":"2026-03-27T01:53:23.48742Z","steps":["trace[1924043517] 'process raft request' (duration: 191.915426ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:53:23.548941Z","caller":"traceutil/trace.go:171","msg":"trace[1347981836] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"130.562447ms","start":"2026-03-27T01:53:23.418346Z","end":"2026-03-27T01:53:23.548908Z","steps":["trace[1347981836] 'process raft request' (duration: 116.162454ms)","trace[1347981836] 'compare' (duration: 14.252576ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:53:23.803842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.650442ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-27T01:53:23.804271Z","caller":"traceutil/trace.go:171","msg":"trace[993379975] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"106.875239ms","start":"2026-03-27T01:53:23.697371Z","end":"2026-03-27T01:53:23.804246Z","steps":["trace[993379975] 'process raft request' (duration: 106.578565ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:53:29.769941Z","caller":"traceutil/trace.go:171","msg":"trace[1843593120] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"123.981121ms","start":"2026-03-27T01:53:29.645929Z","end":"2026-03-27T01:53:29.76991Z","steps":["trace[1843593120] 'process raft request' (duration: 123.793325ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:53:35.765323Z","caller":"traceutil/trace.go:171","msg":"trace[1479598683] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"134.796425ms","start":"2026-03-27T01:53:35.630499Z","end":"2026-03-27T01:53:35.765295Z","steps":["trace[1479598683] 'read index received' (duration: 134.555127ms)","trace[1479598683] 'applied index is now lower than readState.Index' (duration: 240.098µs)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:53:35.765465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.948192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:53:35.765451Z","caller":"traceutil/trace.go:171","msg":"trace[681642809] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"220.756758ms","start":"2026-03-27T01:53:35.544655Z","end":"2026-03-27T01:53:35.765412Z","steps":["trace[681642809] 'process raft request' (duration: 220.468231ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:53:35.765512Z","caller":"traceutil/trace.go:171","msg":"trace[1477107031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"135.076821ms","start":"2026-03-27T01:53:35.630422Z","end":"2026-03-27T01:53:35.765499Z","steps":["trace[1477107031] 'agreement among raft nodes before linearized reading' (duration: 134.980232ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:53:37.89198Z","caller":"traceutil/trace.go:171","msg":"trace[1548387479] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"118.02761ms","start":"2026-03-27T01:53:37.773922Z","end":"2026-03-27T01:53:37.89195Z","steps":["trace[1548387479] 'process raft request' (duration: 117.818982ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:53:38.043474Z","caller":"traceutil/trace.go:171","msg":"trace[359088213] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"146.973903ms","start":"2026-03-27T01:53:37.896463Z","end":"2026-03-27T01:53:38.043437Z","steps":["trace[359088213] 'process raft request' (duration: 113.466407ms)","trace[359088213] 'compare' (duration: 33.360148ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:53:38.225281Z","caller":"traceutil/trace.go:171","msg":"trace[826163069] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"150.212683ms","start":"2026-03-27T01:53:38.07502Z","end":"2026-03-27T01:53:38.225233Z","steps":["trace[826163069] 'process raft request' (duration: 103.533272ms)","trace[826163069] 'compare' (duration: 46.471413ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:53:39.294601Z","caller":"traceutil/trace.go:171","msg":"trace[1743341810] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"292.225391ms","start":"2026-03-27T01:53:39.002327Z","end":"2026-03-27T01:53:39.294552Z","steps":["trace[1743341810] 'process raft request' (duration: 291.991734ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:53:40.041754Z","caller":"traceutil/trace.go:171","msg":"trace[1556454636] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"137.35512ms","start":"2026-03-27T01:53:39.904369Z","end":"2026-03-27T01:53:40.041724Z","steps":["trace[1556454636] 'process raft request' (duration: 137.176102ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:53:40.508372Z","caller":"traceutil/trace.go:171","msg":"trace[1057535778] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"274.80067ms","start":"2026-03-27T01:53:40.233541Z","end":"2026-03-27T01:53:40.508342Z","steps":["trace[1057535778] 'process raft request' (duration: 274.653402ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:54:00.454441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.005127ms","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-03-27T01:54:00.454532Z","caller":"traceutil/trace.go:171","msg":"trace[927641247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1391; }","duration":"107.151437ms","start":"2026-03-27T01:54:00.347362Z","end":"2026-03-27T01:54:00.454514Z","steps":["trace[927641247] 'range keys from in-memory index tree' (duration: 106.863409ms)"],"step_count":1}