{"level":"warn","ts":"2026-03-18T21:48:15.695529Z","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-18T21:48:15.695613Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-18T21:48:15.695697Z","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-18T21:48:15.69571Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-03-18T21:48:15.695729Z","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-18T21:48:15.696394Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-03-18T21:48:15.696467Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-18T21:48:15.702307Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.633223ms"} {"level":"info","ts":"2026-03-18T21:48:15.710427Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-03-18T21:48:15.710529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T21:48:15.710563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-03-18T21:48:15.710589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T21:48:15.710608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-03-18T21:48:15.710675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-03-18T21:48:15.718752Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T21:48:15.721509Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T21:48:15.724203Z","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-18T21:48:15.72943Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T21:48:15.729606Z","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-18T21:48:15.730429Z","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-18T21:48:15.730813Z","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-18T21:48:15.7307Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T21:48:15.732008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-03-18T21:48:15.732157Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-03-18T21:48:15.736162Z","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-18T21:48:15.736349Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T21:48:15.736373Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T21:48:15.736434Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-03-18T21:48:15.736446Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-03-18T21:48:16.310888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T21:48:16.310943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T21:48:16.311002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-03-18T21:48:16.311028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-03-18T21:48:16.311045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-03-18T21:48:16.311066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-03-18T21:48:16.311085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-03-18T21:48:16.312117Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T21:48:16.31411Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T21:48:16.314136Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T21:48:16.314162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T21:48:16.314505Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T21:48:16.314543Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T21:48:16.314799Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T21:48:16.31496Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T21:48:16.314997Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T21:48:16.317904Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-03-18T21:48:16.318289Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-18T21:48:25.834396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.476948ms","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":"warn","ts":"2026-03-18T21:48:25.834361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.915014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-18T21:48:25.834504Z","caller":"traceutil/trace.go:171","msg":"trace[1784487764] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:311; }","duration":"191.598711ms","start":"2026-03-18T21:48:25.642879Z","end":"2026-03-18T21:48:25.834477Z","steps":["trace[1784487764] 'range keys from in-memory index tree' (duration: 191.324823ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:48:25.834521Z","caller":"traceutil/trace.go:171","msg":"trace[337827749] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:311; }","duration":"141.190781ms","start":"2026-03-18T21:48:25.693305Z","end":"2026-03-18T21:48:25.834495Z","steps":["trace[337827749] 'range keys from in-memory index tree' (duration: 140.71199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:25.834485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.797099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-18T21:48:25.835034Z","caller":"traceutil/trace.go:171","msg":"trace[213199656] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:311; }","duration":"243.410924ms","start":"2026-03-18T21:48:25.591597Z","end":"2026-03-18T21:48:25.835008Z","steps":["trace[213199656] 'range keys from in-memory index tree' (duration: 242.534102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:31.306857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604552990581083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T21:48:31.778675Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.235396258s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T21:48:31.780508Z","caller":"traceutil/trace.go:171","msg":"trace[1014913596] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"974.010828ms","start":"2026-03-18T21:48:30.806473Z","end":"2026-03-18T21:48:31.780483Z","steps":["trace[1014913596] 'read index received' (duration: 972.603411ms)","trace[1014913596] 'applied index is now lower than readState.Index' (duration: 1.406467ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:48:31.780658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.153621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-18T21:48:31.780687Z","caller":"traceutil/trace.go:171","msg":"trace[909803310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:370; }","duration":"974.262933ms","start":"2026-03-18T21:48:30.806417Z","end":"2026-03-18T21:48:31.78068Z","steps":["trace[909803310] 'agreement among raft nodes before linearized reading' (duration: 974.159171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:31.780714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:30.806386Z","time spent":"974.322785ms","remote":"127.0.0.1:47428","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-18T21:48:31.780776Z","caller":"traceutil/trace.go:171","msg":"trace[514764576] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"1.237544032s","start":"2026-03-18T21:48:30.543169Z","end":"2026-03-18T21:48:31.780713Z","steps":["trace[514764576] 'process raft request' (duration: 1.236548316s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:31.781575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:30.543144Z","time spent":"1.237740808s","remote":"127.0.0.1:47332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T21:48:32.287772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604552990581086,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T21:48:32.786684Z","caller":"traceutil/trace.go:171","msg":"trace[1244957537] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"1.444215055s","start":"2026-03-18T21:48:31.34242Z","end":"2026-03-18T21:48:32.786635Z","steps":["trace[1244957537] 'process raft request' (duration: 1.373707086s)","trace[1244957537] 'compare' (duration: 70.320764ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:48:32.786935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:31.342392Z","time spent":"1.444435731s","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:48:32.786946Z","caller":"traceutil/trace.go:171","msg":"trace[1369605318] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:386; }","duration":"999.618107ms","start":"2026-03-18T21:48:31.787173Z","end":"2026-03-18T21:48:32.786791Z","steps":["trace[1369605318] 'read index received' (duration: 928.967784ms)","trace[1369605318] 'applied index is now lower than readState.Index' (duration: 70.646853ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:48:32.787297Z","caller":"traceutil/trace.go:171","msg":"trace[1640993066] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"999.139846ms","start":"2026-03-18T21:48:31.78813Z","end":"2026-03-18T21:48:32.78727Z","steps":["trace[1640993066] 'process raft request' (duration: 998.455438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:32.787364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000171141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5335"} {"level":"warn","ts":"2026-03-18T21:48:32.787422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.431726ms","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-18T21:48:32.787461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.521176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T21:48:32.787549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.345825ms","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-18T21:48:32.787577Z","caller":"traceutil/trace.go:171","msg":"trace[1428387521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:372; }","duration":"716.713341ms","start":"2026-03-18T21:48:32.070844Z","end":"2026-03-18T21:48:32.787557Z","steps":["trace[1428387521] 'agreement among raft nodes before linearized reading' (duration: 716.506306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:32.787559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.7052ms","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-18T21:48:32.78766Z","caller":"traceutil/trace.go:171","msg":"trace[512541234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:372; }","duration":"849.481777ms","start":"2026-03-18T21:48:31.938142Z","end":"2026-03-18T21:48:32.787624Z","steps":["trace[512541234] 'agreement among raft nodes before linearized reading' (duration: 849.307533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:32.787718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:32.070828Z","time spent":"716.877354ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T21:48:32.787731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:31.93813Z","time spent":"849.592901ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-18T21:48:32.787529Z","caller":"traceutil/trace.go:171","msg":"trace[126784502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:372; }","duration":"916.648192ms","start":"2026-03-18T21:48:31.870867Z","end":"2026-03-18T21:48:32.787516Z","steps":["trace[126784502] 'agreement among raft nodes before linearized reading' (duration: 916.439367ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:48:32.787465Z","caller":"traceutil/trace.go:171","msg":"trace[2084648196] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:372; }","duration":"1.000323975s","start":"2026-03-18T21:48:31.787117Z","end":"2026-03-18T21:48:32.787441Z","steps":["trace[2084648196] 'agreement among raft nodes before linearized reading' (duration: 1.00013932s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:32.787887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:31.787092Z","time spent":"1.000766256s","remote":"127.0.0.1:47332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5358,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-03-18T21:48:32.78789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:31.870841Z","time spent":"916.993601ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T21:48:32.787453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:31.788095Z","time spent":"999.267118ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:48:32.787908Z","caller":"traceutil/trace.go:171","msg":"trace[2067195938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:372; }","duration":"485.996747ms","start":"2026-03-18T21:48:32.301783Z","end":"2026-03-18T21:48:32.78778Z","steps":["trace[2067195938] 'agreement among raft nodes before linearized reading' (duration: 485.552266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:32.78805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:32.301758Z","time spent":"486.275255ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T21:48:51.075642Z","caller":"traceutil/trace.go:171","msg":"trace[1826823951] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"149.79455ms","start":"2026-03-18T21:48:50.925807Z","end":"2026-03-18T21:48:51.075602Z","steps":["trace[1826823951] 'process raft request' (duration: 149.434371ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:48:51.075905Z","caller":"traceutil/trace.go:171","msg":"trace[1710319237] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"148.034265ms","start":"2026-03-18T21:48:50.927861Z","end":"2026-03-18T21:48:51.075895Z","steps":["trace[1710319237] 'process raft request' (duration: 147.707306ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:48:51.075665Z","caller":"traceutil/trace.go:171","msg":"trace[1986619022] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"149.742048ms","start":"2026-03-18T21:48:50.925873Z","end":"2026-03-18T21:48:51.075615Z","steps":["trace[1986619022] 'process raft request' (duration: 149.650226ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:48:57.27178Z","caller":"traceutil/trace.go:171","msg":"trace[720165455] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"183.051293ms","start":"2026-03-18T21:48:57.088699Z","end":"2026-03-18T21:48:57.27175Z","steps":["trace[720165455] 'read index received' (duration: 105.829765ms)","trace[720165455] 'applied index is now lower than readState.Index' (duration: 77.220528ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:48:57.272067Z","caller":"traceutil/trace.go:171","msg":"trace[1383228706] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"260.747854ms","start":"2026-03-18T21:48:57.011303Z","end":"2026-03-18T21:48:57.27205Z","steps":["trace[1383228706] 'process raft request' (duration: 183.30224ms)","trace[1383228706] 'compare' (duration: 76.953722ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:48:57.272338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.619637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8476z\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-18T21:48:57.2724Z","caller":"traceutil/trace.go:171","msg":"trace[1873325247] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8476z; range_end:; response_count:1; response_revision:501; }","duration":"183.73039ms","start":"2026-03-18T21:48:57.088651Z","end":"2026-03-18T21:48:57.272382Z","steps":["trace[1873325247] 'agreement among raft nodes before linearized reading' (duration: 183.509885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:57.272397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.906226ms","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-18T21:48:57.272451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.977858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-18T21:48:57.272495Z","caller":"traceutil/trace.go:171","msg":"trace[337055500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:501; }","duration":"175.071981ms","start":"2026-03-18T21:48:57.097399Z","end":"2026-03-18T21:48:57.272471Z","steps":["trace[337055500] 'agreement among raft nodes before linearized reading' (duration: 174.778933ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:48:57.272538Z","caller":"traceutil/trace.go:171","msg":"trace[2025082761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:501; }","duration":"171.134252ms","start":"2026-03-18T21:48:57.101382Z","end":"2026-03-18T21:48:57.272516Z","steps":["trace[2025082761] 'agreement among raft nodes before linearized reading' (duration: 170.822593ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:48:57.757423Z","caller":"traceutil/trace.go:171","msg":"trace[1036725763] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"480.673563ms","start":"2026-03-18T21:48:57.276724Z","end":"2026-03-18T21:48:57.757397Z","steps":["trace[1036725763] 'process raft request' (duration: 390.266629ms)","trace[1036725763] 'compare' (duration: 90.306942ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:48:57.75758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:57.276702Z","time spent":"480.782636ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:48:57.852565Z","caller":"traceutil/trace.go:171","msg":"trace[1869535994] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:524; }","duration":"277.081589ms","start":"2026-03-18T21:48:57.575459Z","end":"2026-03-18T21:48:57.852541Z","steps":["trace[1869535994] 'read index received' (duration: 91.49887ms)","trace[1869535994] 'applied index is now lower than readState.Index' (duration: 185.581659ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:48:57.852776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.290403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:48:57.852814Z","caller":"traceutil/trace.go:171","msg":"trace[302869713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:504; }","duration":"277.411147ms","start":"2026-03-18T21:48:57.575388Z","end":"2026-03-18T21:48:57.8528Z","steps":["trace[302869713] 'agreement among raft nodes before linearized reading' (duration: 277.317455ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:48:57.8528Z","caller":"traceutil/trace.go:171","msg":"trace[1077321841] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"566.990964ms","start":"2026-03-18T21:48:57.285785Z","end":"2026-03-18T21:48:57.852775Z","steps":["trace[1077321841] 'process raft request' (duration: 566.570613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:57.853035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:57.285777Z","time spent":"567.171078ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:48:57.853144Z","caller":"traceutil/trace.go:171","msg":"trace[31679840] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"557.121803ms","start":"2026-03-18T21:48:57.29577Z","end":"2026-03-18T21:48:57.852892Z","steps":["trace[31679840] 'process raft request' (duration: 556.693172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:57.853326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:57.295759Z","time spent":"557.468351ms","remote":"127.0.0.1:47332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:48:58.919906Z","caller":"traceutil/trace.go:171","msg":"trace[1763855022] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"346.405238ms","start":"2026-03-18T21:48:58.573472Z","end":"2026-03-18T21:48:58.919877Z","steps":["trace[1763855022] 'read index received' (duration: 346.121709ms)","trace[1763855022] 'applied index is now lower than readState.Index' (duration: 282.619µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:48:58.920043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.550341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:48:58.920075Z","caller":"traceutil/trace.go:171","msg":"trace[190217879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"346.653264ms","start":"2026-03-18T21:48:58.573411Z","end":"2026-03-18T21:48:58.920064Z","steps":["trace[190217879] 'agreement among raft nodes before linearized reading' (duration: 346.575002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:58.920116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:58.573388Z","time spent":"346.721045ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T21:48:58.920249Z","caller":"traceutil/trace.go:171","msg":"trace[215223960] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"477.37137ms","start":"2026-03-18T21:48:58.442838Z","end":"2026-03-18T21:48:58.920209Z","steps":["trace[215223960] 'process raft request' (duration: 476.868747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:58.920415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:58.442809Z","time spent":"477.513024ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:48:59.544445Z","caller":"traceutil/trace.go:171","msg":"trace[729917283] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"267.510456ms","start":"2026-03-18T21:48:59.276885Z","end":"2026-03-18T21:48:59.544395Z","steps":["trace[729917283] 'read index received' (duration: 266.09384ms)","trace[729917283] 'applied index is now lower than readState.Index' (duration: 1.414386ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:48:59.544516Z","caller":"traceutil/trace.go:171","msg":"trace[310798097] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"529.83299ms","start":"2026-03-18T21:48:59.014578Z","end":"2026-03-18T21:48:59.54441Z","steps":["trace[310798097] 'process raft request' (duration: 528.455155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:59.544768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.863055ms","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-18T21:48:59.545386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:48:59.014551Z","time spent":"530.515898ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:48:59.545406Z","caller":"traceutil/trace.go:171","msg":"trace[1741935186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:506; }","duration":"268.533491ms","start":"2026-03-18T21:48:59.276833Z","end":"2026-03-18T21:48:59.545366Z","steps":["trace[1741935186] 'agreement among raft nodes before linearized reading' (duration: 267.771402ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:48:59.545428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.093712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-18T21:48:59.545598Z","caller":"traceutil/trace.go:171","msg":"trace[208605749] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:506; }","duration":"205.39612ms","start":"2026-03-18T21:48:59.340186Z","end":"2026-03-18T21:48:59.545582Z","steps":["trace[208605749] 'agreement among raft nodes before linearized reading' (duration: 204.917288ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:01.617781Z","caller":"traceutil/trace.go:171","msg":"trace[522184693] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:560; }","duration":"153.265053ms","start":"2026-03-18T21:49:01.464497Z","end":"2026-03-18T21:49:01.617762Z","steps":["trace[522184693] 'read index received' (duration: 153.193261ms)","trace[522184693] 'applied index is now lower than readState.Index' (duration: 70.522µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:49:01.618057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.505568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:49:01.618149Z","caller":"traceutil/trace.go:171","msg":"trace[625259863] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:537; }","duration":"153.693852ms","start":"2026-03-18T21:49:01.46443Z","end":"2026-03-18T21:49:01.618124Z","steps":["trace[625259863] 'agreement among raft nodes before linearized reading' (duration: 153.454266ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:01.61933Z","caller":"traceutil/trace.go:171","msg":"trace[1625126125] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"350.412359ms","start":"2026-03-18T21:49:01.267886Z","end":"2026-03-18T21:49:01.618298Z","steps":["trace[1625126125] 'process raft request' (duration: 349.765805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:49:01.61954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:49:01.267859Z","time spent":"351.532254ms","remote":"127.0.0.1:47228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T21:49:19.470429Z","caller":"traceutil/trace.go:171","msg":"trace[123902406] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"259.322731ms","start":"2026-03-18T21:49:19.211077Z","end":"2026-03-18T21:49:19.4704Z","steps":["trace[123902406] 'process raft request' (duration: 259.179618ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:19.470495Z","caller":"traceutil/trace.go:171","msg":"trace[1422946007] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:723; }","duration":"128.7603ms","start":"2026-03-18T21:49:19.341708Z","end":"2026-03-18T21:49:19.470468Z","steps":["trace[1422946007] 'read index received' (duration: 128.75074ms)","trace[1422946007] 'applied index is now lower than readState.Index' (duration: 7.84µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:49:19.470699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.974955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-18T21:49:19.470753Z","caller":"traceutil/trace.go:171","msg":"trace[1780427956] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:695; }","duration":"129.097828ms","start":"2026-03-18T21:49:19.341644Z","end":"2026-03-18T21:49:19.470742Z","steps":["trace[1780427956] 'agreement among raft nodes before linearized reading' (duration: 128.908004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:49:20.032176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.756962ms","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-18T21:49:20.032215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.981287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-18T21:49:20.03226Z","caller":"traceutil/trace.go:171","msg":"trace[930660047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:698; }","duration":"263.854575ms","start":"2026-03-18T21:49:19.768382Z","end":"2026-03-18T21:49:20.032237Z","steps":["trace[930660047] 'range keys from in-memory index tree' (duration: 263.614629ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:20.032295Z","caller":"traceutil/trace.go:171","msg":"trace[1011608593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:698; }","duration":"318.083119ms","start":"2026-03-18T21:49:19.714192Z","end":"2026-03-18T21:49:20.032275Z","steps":["trace[1011608593] 'range keys from in-memory index tree' (duration: 317.782323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:49:20.032339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:49:19.714137Z","time spent":"318.192773ms","remote":"127.0.0.1:47428","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-18T21:49:20.032172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.105933ms","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-18T21:49:20.032528Z","caller":"traceutil/trace.go:171","msg":"trace[2081144685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:698; }","duration":"154.537903ms","start":"2026-03-18T21:49:19.877977Z","end":"2026-03-18T21:49:20.032515Z","steps":["trace[2081144685] 'range keys from in-memory index tree' (duration: 153.901429ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:20.391483Z","caller":"traceutil/trace.go:171","msg":"trace[1113070042] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"354.325549ms","start":"2026-03-18T21:49:20.037125Z","end":"2026-03-18T21:49:20.39145Z","steps":["trace[1113070042] 'process raft request' (duration: 354.243627ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:20.391527Z","caller":"traceutil/trace.go:171","msg":"trace[808067323] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"354.890431ms","start":"2026-03-18T21:49:20.03659Z","end":"2026-03-18T21:49:20.39148Z","steps":["trace[808067323] 'process raft request' (duration: 354.566824ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:20.391691Z","caller":"traceutil/trace.go:171","msg":"trace[359020911] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"354.485812ms","start":"2026-03-18T21:49:20.037159Z","end":"2026-03-18T21:49:20.391645Z","steps":["trace[359020911] 'process raft request' (duration: 354.248737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:49:20.391722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:49:20.037111Z","time spent":"354.474132ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T21:49:20.391782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:49:20.036563Z","time spent":"355.080455ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T21:49:20.391888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:49:20.037146Z","time spent":"354.634015ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:49:33.676279Z","caller":"traceutil/trace.go:171","msg":"trace[669196292] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"135.045153ms","start":"2026-03-18T21:49:33.541182Z","end":"2026-03-18T21:49:33.676227Z","steps":["trace[669196292] 'process raft request' (duration: 134.793357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:49:47.604274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.786345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T21:49:47.604365Z","caller":"traceutil/trace.go:171","msg":"trace[938305341] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"928.764434ms","start":"2026-03-18T21:49:46.675585Z","end":"2026-03-18T21:49:47.60435Z","steps":["trace[938305341] 'process raft request' (duration: 771.721853ms)","trace[938305341] 'compare' (duration: 156.5801ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:49:47.604411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:49:46.675553Z","time spent":"928.838915ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T21:49:50.094955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.676435ms","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-18T21:49:50.095024Z","caller":"traceutil/trace.go:171","msg":"trace[664582993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:785; }","duration":"346.799828ms","start":"2026-03-18T21:49:49.748209Z","end":"2026-03-18T21:49:50.095009Z","steps":["trace[664582993] 'range keys from in-memory index tree' (duration: 346.518381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:49:50.095053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:49:49.748185Z","time spent":"346.862159ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T21:49:50.095054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.138699ms","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-18T21:49:50.095138Z","caller":"traceutil/trace.go:171","msg":"trace[675022179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:785; }","duration":"487.281413ms","start":"2026-03-18T21:49:49.607837Z","end":"2026-03-18T21:49:50.095118Z","steps":["trace[675022179] 'range keys from in-memory index tree' (duration: 486.937054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:49:50.09519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:49:49.607817Z","time spent":"487.363945ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-18T21:49:50.182697Z","caller":"traceutil/trace.go:171","msg":"trace[1712221245] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"110.460657ms","start":"2026-03-18T21:49:50.072213Z","end":"2026-03-18T21:49:50.182674Z","steps":["trace[1712221245] 'process raft request' (duration: 110.334854ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:50.215133Z","caller":"traceutil/trace.go:171","msg":"trace[1624388837] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"115.988061ms","start":"2026-03-18T21:49:50.09912Z","end":"2026-03-18T21:49:50.215108Z","steps":["trace[1624388837] 'process raft request' (duration: 96.784647ms)","trace[1624388837] 'compare' (duration: 19.03052ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:49:50.215554Z","caller":"traceutil/trace.go:171","msg":"trace[319441894] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"115.503271ms","start":"2026-03-18T21:49:50.100032Z","end":"2026-03-18T21:49:50.215535Z","steps":["trace[319441894] 'process raft request' (duration: 115.035129ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:52.326972Z","caller":"traceutil/trace.go:171","msg":"trace[1186052334] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"102.549748ms","start":"2026-03-18T21:49:52.224401Z","end":"2026-03-18T21:49:52.32695Z","steps":["trace[1186052334] 'process raft request' (duration: 102.176029ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:54.530341Z","caller":"traceutil/trace.go:171","msg":"trace[753423222] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"181.869581ms","start":"2026-03-18T21:49:54.348445Z","end":"2026-03-18T21:49:54.530314Z","steps":["trace[753423222] 'process raft request' (duration: 181.661597ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:55.007787Z","caller":"traceutil/trace.go:171","msg":"trace[1087989804] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"645.203084ms","start":"2026-03-18T21:49:54.362554Z","end":"2026-03-18T21:49:55.007757Z","steps":["trace[1087989804] 'read index received' (duration: 168.269484ms)","trace[1087989804] 'applied index is now lower than readState.Index' (duration: 476.932619ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:49:55.007875Z","caller":"traceutil/trace.go:171","msg":"trace[978887696] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"657.00637ms","start":"2026-03-18T21:49:54.350831Z","end":"2026-03-18T21:49:55.007838Z","steps":["trace[978887696] 'process raft request' (duration: 656.789155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:49:55.008039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.461879ms","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-18T21:49:55.008276Z","caller":"traceutil/trace.go:171","msg":"trace[170627922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"645.728635ms","start":"2026-03-18T21:49:54.362534Z","end":"2026-03-18T21:49:55.008262Z","steps":["trace[170627922] 'agreement among raft nodes before linearized reading' (duration: 645.386207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:49:55.008312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:49:54.362524Z","time spent":"645.779466ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T21:49:55.008685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:49:54.350818Z","time spent":"657.381928ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T21:49:55.095148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.772839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T21:49:55.095172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.932861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:49:55.095655Z","caller":"traceutil/trace.go:171","msg":"trace[1535985470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"520.454114ms","start":"2026-03-18T21:49:54.575184Z","end":"2026-03-18T21:49:55.095638Z","steps":["trace[1535985470] 'agreement among raft nodes before linearized reading' (duration: 519.507952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:49:55.095706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:49:54.575165Z","time spent":"520.528676ms","remote":"127.0.0.1:47176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T21:49:55.095228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.818581ms","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-18T21:49:55.095546Z","caller":"traceutil/trace.go:171","msg":"trace[1128788650] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:800; }","duration":"186.180607ms","start":"2026-03-18T21:49:54.909302Z","end":"2026-03-18T21:49:55.095483Z","steps":["trace[1128788650] 'agreement among raft nodes before linearized reading' (duration: 185.41176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:49:55.095263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.215414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-18T21:49:55.0965Z","caller":"traceutil/trace.go:171","msg":"trace[1249039816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:800; }","duration":"406.453987ms","start":"2026-03-18T21:49:54.689362Z","end":"2026-03-18T21:49:55.095816Z","steps":["trace[1249039816] 'agreement among raft nodes before linearized reading' (duration: 405.365892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:49:55.096855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:49:54.689341Z","time spent":"407.4648ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T21:49:55.096596Z","caller":"traceutil/trace.go:171","msg":"trace[2112947310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:800; }","duration":"274.574426ms","start":"2026-03-18T21:49:54.821986Z","end":"2026-03-18T21:49:55.096561Z","steps":["trace[2112947310] 'agreement among raft nodes before linearized reading' (duration: 272.735524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:09.825653Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604552990582234,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T21:50:10.191685Z","caller":"traceutil/trace.go:171","msg":"trace[935967915] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"894.466899ms","start":"2026-03-18T21:50:09.297187Z","end":"2026-03-18T21:50:10.191654Z","steps":["trace[935967915] 'process raft request' (duration: 894.244894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:10.191886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:09.297178Z","time spent":"894.602072ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:50:10.825873Z","caller":"traceutil/trace.go:171","msg":"trace[1466059196] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"1.52799202s","start":"2026-03-18T21:50:09.297852Z","end":"2026-03-18T21:50:10.825844Z","steps":["trace[1466059196] 'process raft request' (duration: 1.527584911s)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:10.825987Z","caller":"traceutil/trace.go:171","msg":"trace[1775153401] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"1.528089772s","start":"2026-03-18T21:50:09.297874Z","end":"2026-03-18T21:50:10.825963Z","steps":["trace[1775153401] 'process raft request' (duration: 1.527807596s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:10.826028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:09.297846Z","time spent":"1.528103593s","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T21:50:10.826101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:09.297867Z","time spent":"1.528170235s","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:50:10.826633Z","caller":"traceutil/trace.go:171","msg":"trace[1430286742] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:928; }","duration":"1.501328317s","start":"2026-03-18T21:50:09.325284Z","end":"2026-03-18T21:50:10.826612Z","steps":["trace[1430286742] 'read index received' (duration: 866.144748ms)","trace[1430286742] 'applied index is now lower than readState.Index' (duration: 635.182419ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:10.826863Z","caller":"traceutil/trace.go:171","msg":"trace[1472565126] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"1.528929331s","start":"2026-03-18T21:50:09.297918Z","end":"2026-03-18T21:50:10.826847Z","steps":["trace[1472565126] 'process raft request' (duration: 1.527855647s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:10.826937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:09.297912Z","time spent":"1.528981082s","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T21:50:10.827144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.501829328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:50:10.827181Z","caller":"traceutil/trace.go:171","msg":"trace[1122612287] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:891; }","duration":"1.50192259s","start":"2026-03-18T21:50:09.325246Z","end":"2026-03-18T21:50:10.827169Z","steps":["trace[1122612287] 'agreement among raft nodes before linearized reading' (duration: 1.501841928s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:10.827215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:09.325228Z","time spent":"1.501979631s","remote":"127.0.0.1:47496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-03-18T21:50:11.016116Z","caller":"traceutil/trace.go:171","msg":"trace[1610583168] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"189.392712ms","start":"2026-03-18T21:50:10.826694Z","end":"2026-03-18T21:50:11.016087Z","steps":["trace[1610583168] 'read index received' (duration: 141.194451ms)","trace[1610583168] 'applied index is now lower than readState.Index' (duration: 48.196801ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:50:11.016545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.28141ms","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-18T21:50:11.01661Z","caller":"traceutil/trace.go:171","msg":"trace[121682323] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:892; }","duration":"393.400663ms","start":"2026-03-18T21:50:10.623192Z","end":"2026-03-18T21:50:11.016593Z","steps":["trace[121682323] 'agreement among raft nodes before linearized reading' (duration: 393.225179ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:11.016649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:10.623171Z","time spent":"393.469794ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-18T21:50:11.016698Z","caller":"traceutil/trace.go:171","msg":"trace[773872833] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"305.24534ms","start":"2026-03-18T21:50:10.71143Z","end":"2026-03-18T21:50:11.016676Z","steps":["trace[773872833] 'process raft request' (duration: 256.483677ms)","trace[773872833] 'compare' (duration: 48.034957ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:50:11.016845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:10.711408Z","time spent":"305.334691ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T21:50:11.017166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672125328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-18T21:50:11.017202Z","caller":"traceutil/trace.go:171","msg":"trace[383464310] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:892; }","duration":"1.672184028s","start":"2026-03-18T21:50:09.345007Z","end":"2026-03-18T21:50:11.017191Z","steps":["trace[383464310] 'agreement among raft nodes before linearized reading' (duration: 1.672092506s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:11.01723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:09.344995Z","time spent":"1.672228759s","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.140\" "} {"level":"warn","ts":"2026-03-18T21:50:11.017462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441599057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:50:11.017521Z","caller":"traceutil/trace.go:171","msg":"trace[745746477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"1.442692131s","start":"2026-03-18T21:50:09.574811Z","end":"2026-03-18T21:50:11.017503Z","steps":["trace[745746477] 'agreement among raft nodes before linearized reading' (duration: 1.441606977s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:11.017584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:09.574789Z","time spent":"1.442762052s","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T21:50:23.331995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.544627ms","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-18T21:50:23.33209Z","caller":"traceutil/trace.go:171","msg":"trace[1303059314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:926; }","duration":"226.69168ms","start":"2026-03-18T21:50:23.10538Z","end":"2026-03-18T21:50:23.332071Z","steps":["trace[1303059314] 'range keys from in-memory index tree' (duration: 226.389324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:26.670184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.876526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-18T21:50:26.67032Z","caller":"traceutil/trace.go:171","msg":"trace[2011379195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"418.007239ms","start":"2026-03-18T21:50:26.25229Z","end":"2026-03-18T21:50:26.670298Z","steps":["trace[2011379195] 'range keys from in-memory index tree' (duration: 417.703153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:26.670363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:26.252272Z","time spent":"418.079011ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T21:50:26.669992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.312384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T21:50:26.670755Z","caller":"traceutil/trace.go:171","msg":"trace[2109764400] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:954; }","duration":"596.139363ms","start":"2026-03-18T21:50:26.074605Z","end":"2026-03-18T21:50:26.670744Z","steps":["trace[2109764400] 'count revisions from in-memory index tree' (duration: 595.178501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:26.670791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:26.074582Z","time spent":"596.195163ms","remote":"127.0.0.1:47438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-03-18T21:50:27.205636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.07953ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T21:50:27.205867Z","caller":"traceutil/trace.go:171","msg":"trace[1383762524] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"527.315473ms","start":"2026-03-18T21:50:26.678531Z","end":"2026-03-18T21:50:27.205847Z","steps":["trace[1383762524] 'process raft request' (duration: 527.2068ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:27.205851Z","caller":"traceutil/trace.go:171","msg":"trace[927987664] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"607.029166ms","start":"2026-03-18T21:50:26.598792Z","end":"2026-03-18T21:50:27.205822Z","steps":["trace[927987664] 'read index received' (duration: 91.714052ms)","trace[927987664] 'applied index is now lower than readState.Index' (duration: 515.312714ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:50:27.205967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:26.678501Z","time spent":"527.407635ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T21:50:27.206117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.852444ms","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-18T21:50:27.206166Z","caller":"traceutil/trace.go:171","msg":"trace[1132222724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"146.942716ms","start":"2026-03-18T21:50:27.059206Z","end":"2026-03-18T21:50:27.206149Z","steps":["trace[1132222724] 'agreement among raft nodes before linearized reading' (duration: 146.760602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:27.206035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.226921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-18T21:50:27.206196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.22232ms","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-18T21:50:27.206253Z","caller":"traceutil/trace.go:171","msg":"trace[1689119029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"150.293132ms","start":"2026-03-18T21:50:27.055948Z","end":"2026-03-18T21:50:27.206242Z","steps":["trace[1689119029] 'agreement among raft nodes before linearized reading' (duration: 150.19705ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:27.206256Z","caller":"traceutil/trace.go:171","msg":"trace[842302631] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:955; }","duration":"607.500176ms","start":"2026-03-18T21:50:26.598735Z","end":"2026-03-18T21:50:27.206235Z","steps":["trace[842302631] 'agreement among raft nodes before linearized reading' (duration: 607.2006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:27.206125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.234751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-18T21:50:27.206306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:26.598712Z","time spent":"607.581168ms","remote":"127.0.0.1:47264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-03-18T21:50:27.206319Z","caller":"traceutil/trace.go:171","msg":"trace[2074045512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"155.480527ms","start":"2026-03-18T21:50:27.050827Z","end":"2026-03-18T21:50:27.206308Z","steps":["trace[2074045512] 'agreement among raft nodes before linearized reading' (duration: 155.136709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:27.2065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.051411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:50:27.206579Z","caller":"traceutil/trace.go:171","msg":"trace[1623151316] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:955; }","duration":"152.179273ms","start":"2026-03-18T21:50:27.054386Z","end":"2026-03-18T21:50:27.206565Z","steps":["trace[1623151316] 'agreement among raft nodes before linearized reading' (duration: 151.961509ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:27.349461Z","caller":"traceutil/trace.go:171","msg":"trace[1730846166] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"136.136485ms","start":"2026-03-18T21:50:27.213299Z","end":"2026-03-18T21:50:27.349436Z","steps":["trace[1730846166] 'process raft request' (duration: 136.085373ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:27.349569Z","caller":"traceutil/trace.go:171","msg":"trace[2034922674] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"137.211129ms","start":"2026-03-18T21:50:27.212322Z","end":"2026-03-18T21:50:27.349533Z","steps":["trace[2034922674] 'process raft request' (duration: 129.995958ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:31.531511Z","caller":"traceutil/trace.go:171","msg":"trace[1326113105] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"268.912014ms","start":"2026-03-18T21:50:31.262581Z","end":"2026-03-18T21:50:31.531493Z","steps":["trace[1326113105] 'process raft request' (duration: 268.686759ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:31.531653Z","caller":"traceutil/trace.go:171","msg":"trace[1212695902] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"202.233732ms","start":"2026-03-18T21:50:31.329231Z","end":"2026-03-18T21:50:31.531465Z","steps":["trace[1212695902] 'read index received' (duration: 202.051958ms)","trace[1212695902] 'applied index is now lower than readState.Index' (duration: 180.634µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:50:31.531716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.464147ms","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-18T21:50:31.531773Z","caller":"traceutil/trace.go:171","msg":"trace[1101393194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:972; }","duration":"202.5593ms","start":"2026-03-18T21:50:31.329201Z","end":"2026-03-18T21:50:31.53176Z","steps":["trace[1101393194] 'agreement among raft nodes before linearized reading' (duration: 202.384136ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:31.807653Z","caller":"traceutil/trace.go:171","msg":"trace[752681660] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"273.137249ms","start":"2026-03-18T21:50:31.534489Z","end":"2026-03-18T21:50:31.807627Z","steps":["trace[752681660] 'process raft request' (duration: 273.023696ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:31.807832Z","caller":"traceutil/trace.go:171","msg":"trace[2025578864] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"452.575022ms","start":"2026-03-18T21:50:31.355238Z","end":"2026-03-18T21:50:31.807813Z","steps":["trace[2025578864] 'process raft request' (duration: 450.017304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:31.807967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:31.355222Z","time spent":"452.659653ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:50:31.807974Z","caller":"traceutil/trace.go:171","msg":"trace[2119982923] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"276.287099ms","start":"2026-03-18T21:50:31.531671Z","end":"2026-03-18T21:50:31.807958Z","steps":["trace[2119982923] 'read index received' (duration: 273.596509ms)","trace[2119982923] 'applied index is now lower than readState.Index' (duration: 2.68881ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:50:31.808328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.738664ms","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-18T21:50:31.808405Z","caller":"traceutil/trace.go:171","msg":"trace[129762342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:974; }","duration":"312.855617ms","start":"2026-03-18T21:50:31.49553Z","end":"2026-03-18T21:50:31.808385Z","steps":["trace[129762342] 'agreement among raft nodes before linearized reading' (duration: 312.52542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:31.808407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.170644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T21:50:31.808449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:31.495518Z","time spent":"312.922788ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T21:50:31.808519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.145325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-18T21:50:31.808464Z","caller":"traceutil/trace.go:171","msg":"trace[1252787720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"233.270937ms","start":"2026-03-18T21:50:31.575175Z","end":"2026-03-18T21:50:31.808446Z","steps":["trace[1252787720] 'agreement among raft nodes before linearized reading' (duration: 233.192945ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:31.808581Z","caller":"traceutil/trace.go:171","msg":"trace[1128934752] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:974; }","duration":"335.263648ms","start":"2026-03-18T21:50:31.4733Z","end":"2026-03-18T21:50:31.808564Z","steps":["trace[1128934752] 'agreement among raft nodes before linearized reading' (duration: 335.041273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:31.808623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:31.473293Z","time spent":"335.317149ms","remote":"127.0.0.1:47428","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-18T21:50:31.809126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.92502ms","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-18T21:50:31.80919Z","caller":"traceutil/trace.go:171","msg":"trace[1805485520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:974; }","duration":"348.011213ms","start":"2026-03-18T21:50:31.461164Z","end":"2026-03-18T21:50:31.809175Z","steps":["trace[1805485520] 'agreement among raft nodes before linearized reading' (duration: 346.841847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:31.809239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:31.461152Z","time spent":"348.075254ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-18T21:50:39.451664Z","caller":"traceutil/trace.go:171","msg":"trace[1873840567] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"302.553296ms","start":"2026-03-18T21:50:39.149085Z","end":"2026-03-18T21:50:39.451639Z","steps":["trace[1873840567] 'process raft request' (duration: 302.410973ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:39.451811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:39.149059Z","time spent":"302.677999ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T21:50:39.452273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.335765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-18T21:50:39.452321Z","caller":"traceutil/trace.go:171","msg":"trace[174253754] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:1031; }","duration":"105.465838ms","start":"2026-03-18T21:50:39.346845Z","end":"2026-03-18T21:50:39.45231Z","steps":["trace[174253754] 'agreement among raft nodes before linearized reading' (duration: 105.278234ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:39.451964Z","caller":"traceutil/trace.go:171","msg":"trace[1922194982] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1078; }","duration":"105.018078ms","start":"2026-03-18T21:50:39.346921Z","end":"2026-03-18T21:50:39.451939Z","steps":["trace[1922194982] 'read index received' (duration: 105.010888ms)","trace[1922194982] 'applied index is now lower than readState.Index' (duration: 5.31µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:39.779521Z","caller":"traceutil/trace.go:171","msg":"trace[1904044143] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1078; }","duration":"238.906862ms","start":"2026-03-18T21:50:39.540587Z","end":"2026-03-18T21:50:39.779494Z","steps":["trace[1904044143] 'read index received' (duration: 238.124825ms)","trace[1904044143] 'applied index is now lower than readState.Index' (duration: 780.397µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:50:39.779646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:50:39.453348Z","time spent":"326.295246ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-18T21:50:39.779946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.343722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-18T21:50:39.779998Z","caller":"traceutil/trace.go:171","msg":"trace[845208035] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1031; }","duration":"239.484855ms","start":"2026-03-18T21:50:39.540497Z","end":"2026-03-18T21:50:39.779982Z","steps":["trace[845208035] 'agreement among raft nodes before linearized reading' (duration: 239.324751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:39.781082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.18168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:50:39.781304Z","caller":"traceutil/trace.go:171","msg":"trace[818196566] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1031; }","duration":"193.444676ms","start":"2026-03-18T21:50:39.587809Z","end":"2026-03-18T21:50:39.781253Z","steps":["trace[818196566] 'agreement among raft nodes before linearized reading' (duration: 193.145549ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:39.781623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.74348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:50:39.781759Z","caller":"traceutil/trace.go:171","msg":"trace[1687555205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1031; }","duration":"205.855393ms","start":"2026-03-18T21:50:39.575843Z","end":"2026-03-18T21:50:39.781698Z","steps":["trace[1687555205] 'agreement among raft nodes before linearized reading' (duration: 205.69046ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:39.782509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.979142ms","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-18T21:50:39.782811Z","caller":"traceutil/trace.go:171","msg":"trace[1417884928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1031; }","duration":"217.305548ms","start":"2026-03-18T21:50:39.565491Z","end":"2026-03-18T21:50:39.782797Z","steps":["trace[1417884928] 'agreement among raft nodes before linearized reading' (duration: 214.69177ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:51:16.266503Z","caller":"traceutil/trace.go:171","msg":"trace[1063897048] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"110.145133ms","start":"2026-03-18T21:51:16.156321Z","end":"2026-03-18T21:51:16.266466Z","steps":["trace[1063897048] 'process raft request' (duration: 55.447299ms)","trace[1063897048] 'compare' (duration: 54.563181ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:51:32.438876Z","caller":"traceutil/trace.go:171","msg":"trace[433210006] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"115.747218ms","start":"2026-03-18T21:51:32.323092Z","end":"2026-03-18T21:51:32.43884Z","steps":["trace[433210006] 'process raft request' (duration: 86.10605ms)","trace[433210006] 'compare' (duration: 29.450824ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:52:59.274535Z","caller":"traceutil/trace.go:171","msg":"trace[1294740905] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"135.269801ms","start":"2026-03-18T21:52:59.139231Z","end":"2026-03-18T21:52:59.274501Z","steps":["trace[1294740905] 'process raft request' (duration: 61.680301ms)","trace[1294740905] 'compare' (duration: 73.466757ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:52:59.878176Z","caller":"traceutil/trace.go:171","msg":"trace[1850091236] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"136.598631ms","start":"2026-03-18T21:52:59.741552Z","end":"2026-03-18T21:52:59.878151Z","steps":["trace[1850091236] 'process raft request' (duration: 136.421657ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:53:00.960379Z","caller":"traceutil/trace.go:171","msg":"trace[1496782382] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"110.847565ms","start":"2026-03-18T21:53:00.849477Z","end":"2026-03-18T21:53:00.960324Z","steps":["trace[1496782382] 'process raft request' (duration: 75.524654ms)","trace[1496782382] 'compare' (duration: 34.934993ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:53:56.299167Z","caller":"traceutil/trace.go:171","msg":"trace[2138092721] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"221.59394ms","start":"2026-03-18T21:53:56.077552Z","end":"2026-03-18T21:53:56.299146Z","steps":["trace[2138092721] 'process raft request' (duration: 221.100939ms)"],"step_count":1}