{"level":"warn","ts":"2026-05-20T03:06:54.896163Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T03:06:54.89628Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-05-20T03:06:54.896406Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T03:06:54.89642Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-20T03:06:54.896456Z","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-05-20T03:06:54.897288Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-05-20T03:06:54.897382Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-05-20T03:06:54.901752Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.135344ms"} {"level":"info","ts":"2026-05-20T03:06:54.91206Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-05-20T03:06:54.912179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T03:06:54.912228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-05-20T03:06:54.912248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T03:06:54.912276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-05-20T03:06:54.912318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-05-20T03:06:54.923441Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T03:06:54.924686Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T03:06:54.925787Z","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-05-20T03:06:54.92959Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T03:06:54.929745Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T03:06:54.929846Z","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-05-20T03:06:54.929941Z","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-05-20T03:06:54.929957Z","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-05-20T03:06:54.930818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-05-20T03:06:54.931072Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-20T03:06:54.939629Z","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-05-20T03:06:54.939727Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-20T03:06:54.939772Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-20T03:06:54.939909Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T03:06:54.939961Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T03:06:55.513624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T03:06:55.513678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T03:06:55.513704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-05-20T03:06:55.513718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-05-20T03:06:55.513738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-20T03:06:55.513754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-05-20T03:06:55.513767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-20T03:06:55.711183Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T03:06:55.711205Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:06:55.711227Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T03:06:55.711617Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T03:06:55.711642Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T03:06:55.711179Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T03:06:55.712095Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:06:55.712186Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:06:55.712214Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:06:55.714753Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T03:06:55.715038Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-05-20T03:06:58.56754Z","caller":"traceutil/trace.go:171","msg":"trace[1106824739] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"329.031858ms","start":"2026-05-20T03:06:58.238477Z","end":"2026-05-20T03:06:58.567509Z","steps":["trace[1106824739] 'process raft request' (duration: 318.741253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:06:58.568185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:06:58.238462Z","time spent":"329.169221ms","remote":"127.0.0.1:55194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":657,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:07:01.789861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.192261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:07:01.79021Z","caller":"traceutil/trace.go:171","msg":"trace[656798754] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"905.035599ms","start":"2026-05-20T03:07:00.885149Z","end":"2026-05-20T03:07:01.790185Z","steps":["trace[656798754] 'process raft request' (duration: 904.981829ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:01.790216Z","caller":"traceutil/trace.go:171","msg":"trace[172307044] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:252; }","duration":"908.055335ms","start":"2026-05-20T03:07:00.882095Z","end":"2026-05-20T03:07:01.79015Z","steps":["trace[172307044] 'read index received' (duration: 6.285724ms)","trace[172307044] 'applied index is now lower than readState.Index' (duration: 901.764801ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:07:01.790283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:00.885139Z","time spent":"905.11393ms","remote":"127.0.0.1:55090","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-05-20T03:07:01.790367Z","caller":"traceutil/trace.go:171","msg":"trace[362991046] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"910.136056ms","start":"2026-05-20T03:07:00.880218Z","end":"2026-05-20T03:07:01.790354Z","steps":["trace[362991046] 'compare' (duration: 900.45971ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:01.790341Z","caller":"traceutil/trace.go:171","msg":"trace[1924266344] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"907.143102ms","start":"2026-05-20T03:07:00.883127Z","end":"2026-05-20T03:07:01.79027Z","steps":["trace[1924266344] 'process raft request' (duration: 906.895668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:01.791596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:00.88021Z","time spent":"910.200366ms","remote":"127.0.0.1:54902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:07:01.791793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:00.883111Z","time spent":"908.40481ms","remote":"127.0.0.1:55014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1196,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:07:01.796124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.102246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-20T03:07:01.796204Z","caller":"traceutil/trace.go:171","msg":"trace[1445475055] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:249; }","duration":"916.182957ms","start":"2026-05-20T03:07:00.87999Z","end":"2026-05-20T03:07:01.796172Z","steps":["trace[1445475055] 'agreement among raft nodes before linearized reading' (duration: 912.647774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:01.796239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:00.879984Z","time spent":"916.247778ms","remote":"127.0.0.1:55260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-20T03:07:01.816734Z","caller":"traceutil/trace.go:171","msg":"trace[1541260757] transaction","detail":"{read_only:false; number_of_response:0; response_revision:249; }","duration":"861.027486ms","start":"2026-05-20T03:07:00.955664Z","end":"2026-05-20T03:07:01.816692Z","steps":["trace[1541260757] 'process raft request' (duration: 860.771083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:01.816885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:00.95564Z","time spent":"861.183558ms","remote":"127.0.0.1:54994","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:07:01.817006Z","caller":"traceutil/trace.go:171","msg":"trace[657580969] transaction","detail":"{read_only:false; number_of_response:0; response_revision:252; }","duration":"832.405977ms","start":"2026-05-20T03:07:00.984577Z","end":"2026-05-20T03:07:01.816983Z","steps":["trace[657580969] 'process raft request' (duration: 832.140992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:01.817052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.212249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:07:01.817101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:00.984549Z","time spent":"832.514598ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:07:01.817127Z","caller":"traceutil/trace.go:171","msg":"trace[1839997992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:252; }","duration":"276.369902ms","start":"2026-05-20T03:07:01.540735Z","end":"2026-05-20T03:07:01.817105Z","steps":["trace[1839997992] 'agreement among raft nodes before linearized reading' (duration: 276.25599ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:01.81712Z","caller":"traceutil/trace.go:171","msg":"trace[483025738] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"832.548738ms","start":"2026-05-20T03:07:00.984514Z","end":"2026-05-20T03:07:01.817063Z","steps":["trace[483025738] 'process raft request' (duration: 832.158362ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:01.817091Z","caller":"traceutil/trace.go:171","msg":"trace[2115788289] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"832.7547ms","start":"2026-05-20T03:07:00.984267Z","end":"2026-05-20T03:07:01.817022Z","steps":["trace[2115788289] 'process raft request' (duration: 832.371505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:01.817282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:00.984491Z","time spent":"832.70598ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:07:01.817314Z","caller":"traceutil/trace.go:171","msg":"trace[2091628180] transaction","detail":"{read_only:false; number_of_response:0; response_revision:252; }","duration":"831.835247ms","start":"2026-05-20T03:07:00.985467Z","end":"2026-05-20T03:07:01.817303Z","steps":["trace[2091628180] 'process raft request' (duration: 831.278609ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:01.817341Z","caller":"traceutil/trace.go:171","msg":"trace[1737539405] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"833.170497ms","start":"2026-05-20T03:07:00.984157Z","end":"2026-05-20T03:07:01.817328Z","steps":["trace[1737539405] 'process raft request' (duration: 832.404976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:01.817376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.530744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-20T03:07:01.817402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:00.984134Z","time spent":"833.243529ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:07:01.817363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:00.984233Z","time spent":"833.087157ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:07:01.817416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.712171ms","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-05-20T03:07:01.817448Z","caller":"traceutil/trace.go:171","msg":"trace[376905015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:252; }","duration":"683.635375ms","start":"2026-05-20T03:07:01.133792Z","end":"2026-05-20T03:07:01.817428Z","steps":["trace[376905015] 'agreement among raft nodes before linearized reading' (duration: 683.496904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:01.817502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:01.133776Z","time spent":"683.717618ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-20T03:07:01.817507Z","caller":"traceutil/trace.go:171","msg":"trace[1775864834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:252; }","duration":"375.889283ms","start":"2026-05-20T03:07:01.441594Z","end":"2026-05-20T03:07:01.817483Z","steps":["trace[1775864834] 'agreement among raft nodes before linearized reading' (duration: 375.682621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:01.81739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:00.985452Z","time spent":"831.894518ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:07:01.817551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:01.441559Z","time spent":"375.983234ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T03:07:01.817548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.787489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-20T03:07:01.817645Z","caller":"traceutil/trace.go:171","msg":"trace[316223891] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:252; }","duration":"921.914662ms","start":"2026-05-20T03:07:00.895717Z","end":"2026-05-20T03:07:01.817631Z","steps":["trace[316223891] 'agreement among raft nodes before linearized reading' (duration: 921.062469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:01.817683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:00.895703Z","time spent":"921.969122ms","remote":"127.0.0.1:54940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-20T03:07:02.169853Z","caller":"traceutil/trace.go:171","msg":"trace[1478505041] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"368.948653ms","start":"2026-05-20T03:07:01.800876Z","end":"2026-05-20T03:07:02.169824Z","steps":["trace[1478505041] 'process raft request' (duration: 363.963829ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:02.169986Z","caller":"traceutil/trace.go:171","msg":"trace[1988625453] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"346.113371ms","start":"2026-05-20T03:07:01.823857Z","end":"2026-05-20T03:07:02.16997Z","steps":["trace[1988625453] 'process raft request' (duration: 346.08065ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:02.170105Z","caller":"traceutil/trace.go:171","msg":"trace[1441777312] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"347.616894ms","start":"2026-05-20T03:07:01.822459Z","end":"2026-05-20T03:07:02.170076Z","steps":["trace[1441777312] 'process raft request' (duration: 347.347639ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:02.170157Z","caller":"traceutil/trace.go:171","msg":"trace[1382551094] linearizableReadLoop","detail":"{readStateIndex:263; appliedIndex:261; }","duration":"349.328179ms","start":"2026-05-20T03:07:01.820777Z","end":"2026-05-20T03:07:02.170105Z","steps":["trace[1382551094] 'read index received' (duration: 344.159551ms)","trace[1382551094] 'applied index is now lower than readState.Index' (duration: 5.165048ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:07:02.17024Z","caller":"traceutil/trace.go:171","msg":"trace[1113231780] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"346.898723ms","start":"2026-05-20T03:07:01.823284Z","end":"2026-05-20T03:07:02.170183Z","steps":["trace[1113231780] 'process raft request' (duration: 346.572067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:02.169997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:01.800856Z","time spent":"369.077075ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1934,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:07:02.170385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:01.823276Z","time spent":"347.052425ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:07:02.170233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:01.822436Z","time spent":"347.738295ms","remote":"127.0.0.1:55022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:07:02.170521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.832342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-05-20T03:07:02.170567Z","caller":"traceutil/trace.go:171","msg":"trace[1478644250] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:258; }","duration":"348.932253ms","start":"2026-05-20T03:07:01.821623Z","end":"2026-05-20T03:07:02.170555Z","steps":["trace[1478644250] 'agreement among raft nodes before linearized reading' (duration: 348.833012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:02.1706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:01.821604Z","time spent":"348.990453ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2943,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-20T03:07:02.170583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.728014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-20T03:07:02.170704Z","caller":"traceutil/trace.go:171","msg":"trace[861692662] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:258; }","duration":"349.968708ms","start":"2026-05-20T03:07:01.820721Z","end":"2026-05-20T03:07:02.17069Z","steps":["trace[861692662] 'agreement among raft nodes before linearized reading' (duration: 349.721165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:02.17077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:01.820699Z","time spent":"350.05869ms","remote":"127.0.0.1:54994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-20T03:07:02.170839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.126876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-20T03:07:02.170874Z","caller":"traceutil/trace.go:171","msg":"trace[237904945] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:258; }","duration":"349.170696ms","start":"2026-05-20T03:07:01.821694Z","end":"2026-05-20T03:07:02.170865Z","steps":["trace[237904945] 'agreement among raft nodes before linearized reading' (duration: 349.122365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:02.170901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:01.821689Z","time spent":"349.206356ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-05-20T03:07:02.170349Z","caller":"traceutil/trace.go:171","msg":"trace[949121668] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"369.382409ms","start":"2026-05-20T03:07:01.800956Z","end":"2026-05-20T03:07:02.170338Z","steps":["trace[949121668] 'process raft request' (duration: 368.803841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:02.170992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:01.800949Z","time spent":"369.991868ms","remote":"127.0.0.1:54902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:07:02.170797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.682486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-05-20T03:07:02.171135Z","caller":"traceutil/trace.go:171","msg":"trace[1557206435] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:258; }","duration":"266.06203ms","start":"2026-05-20T03:07:01.905057Z","end":"2026-05-20T03:07:02.171119Z","steps":["trace[1557206435] 'agreement among raft nodes before linearized reading' (duration: 265.709835ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:02.171217Z","caller":"traceutil/trace.go:171","msg":"trace[345381933] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"347.534592ms","start":"2026-05-20T03:07:01.823671Z","end":"2026-05-20T03:07:02.171205Z","steps":["trace[345381933] 'process raft request' (duration: 346.226332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:02.171514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:01.823662Z","time spent":"347.589712ms","remote":"127.0.0.1:55090","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-05-20T03:07:02.171605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:01.823847Z","time spent":"347.703644ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:07:29.426032Z","caller":"traceutil/trace.go:171","msg":"trace[1829778192] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"238.521764ms","start":"2026-05-20T03:07:29.187482Z","end":"2026-05-20T03:07:29.426004Z","steps":["trace[1829778192] 'process raft request' (duration: 167.421667ms)","trace[1829778192] 'compare' (duration: 70.993623ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:07:32.611936Z","caller":"traceutil/trace.go:171","msg":"trace[430103454] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"215.410436ms","start":"2026-05-20T03:07:32.396505Z","end":"2026-05-20T03:07:32.611916Z","steps":["trace[430103454] 'process raft request' (duration: 215.269949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:32.612278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.238873ms","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-05-20T03:07:32.612322Z","caller":"traceutil/trace.go:171","msg":"trace[1800236396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:484; }","duration":"214.339347ms","start":"2026-05-20T03:07:32.397975Z","end":"2026-05-20T03:07:32.612314Z","steps":["trace[1800236396] 'agreement among raft nodes before linearized reading' (duration: 214.210462ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:32.612139Z","caller":"traceutil/trace.go:171","msg":"trace[455803641] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:499; }","duration":"214.083906ms","start":"2026-05-20T03:07:32.398025Z","end":"2026-05-20T03:07:32.612109Z","steps":["trace[455803641] 'read index received' (duration: 214.067865ms)","trace[455803641] 'applied index is now lower than readState.Index' (duration: 13.451µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:07:32.721191Z","caller":"traceutil/trace.go:171","msg":"trace[1542757762] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"247.948138ms","start":"2026-05-20T03:07:32.473209Z","end":"2026-05-20T03:07:32.721157Z","steps":["trace[1542757762] 'process raft request' (duration: 247.77558ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:32.721591Z","caller":"traceutil/trace.go:171","msg":"trace[1455548763] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"192.303811ms","start":"2026-05-20T03:07:32.529269Z","end":"2026-05-20T03:07:32.721573Z","steps":["trace[1455548763] 'process raft request' (duration: 192.237817ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:32.721677Z","caller":"traceutil/trace.go:171","msg":"trace[1750892502] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"248.304485ms","start":"2026-05-20T03:07:32.473355Z","end":"2026-05-20T03:07:32.721659Z","steps":["trace[1750892502] 'process raft request' (duration: 247.719088ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:32.721872Z","caller":"traceutil/trace.go:171","msg":"trace[1671355265] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"246.328238ms","start":"2026-05-20T03:07:32.475535Z","end":"2026-05-20T03:07:32.721863Z","steps":["trace[1671355265] 'process raft request' (duration: 245.566524ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:32.721948Z","caller":"traceutil/trace.go:171","msg":"trace[2095985779] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"214.16276ms","start":"2026-05-20T03:07:32.507778Z","end":"2026-05-20T03:07:32.721941Z","steps":["trace[2095985779] 'process raft request' (duration: 213.350174ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:32.722065Z","caller":"traceutil/trace.go:171","msg":"trace[1208134620] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:502; }","duration":"109.673684ms","start":"2026-05-20T03:07:32.612377Z","end":"2026-05-20T03:07:32.72205Z","steps":["trace[1208134620] 'read index received' (duration: 108.754464ms)","trace[1208134620] 'applied index is now lower than readState.Index' (duration: 918.42µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:07:32.722089Z","caller":"traceutil/trace.go:171","msg":"trace[129026248] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"214.227993ms","start":"2026-05-20T03:07:32.507854Z","end":"2026-05-20T03:07:32.722082Z","steps":["trace[129026248] 'process raft request' (duration: 213.301841ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:32.722136Z","caller":"traceutil/trace.go:171","msg":"trace[646099000] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"192.421446ms","start":"2026-05-20T03:07:32.529705Z","end":"2026-05-20T03:07:32.722126Z","steps":["trace[646099000] 'process raft request' (duration: 192.097722ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:32.722171Z","caller":"traceutil/trace.go:171","msg":"trace[1838555632] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"198.576998ms","start":"2026-05-20T03:07:32.523588Z","end":"2026-05-20T03:07:32.722165Z","steps":["trace[1838555632] 'process raft request' (duration: 197.606114ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:32.722216Z","caller":"traceutil/trace.go:171","msg":"trace[1169612463] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"194.234077ms","start":"2026-05-20T03:07:32.527977Z","end":"2026-05-20T03:07:32.722211Z","steps":["trace[1169612463] 'process raft request' (duration: 193.506405ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:32.722324Z","caller":"traceutil/trace.go:171","msg":"trace[1850871760] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"179.116356ms","start":"2026-05-20T03:07:32.543198Z","end":"2026-05-20T03:07:32.722314Z","steps":["trace[1850871760] 'process raft request' (duration: 178.711748ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:32.722405Z","caller":"traceutil/trace.go:171","msg":"trace[966348591] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"170.326276ms","start":"2026-05-20T03:07:32.552071Z","end":"2026-05-20T03:07:32.722397Z","steps":["trace[966348591] 'process raft request' (duration: 169.891876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:32.722562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.201746ms","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-05-20T03:07:32.722598Z","caller":"traceutil/trace.go:171","msg":"trace[1682377964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:495; }","duration":"318.268078ms","start":"2026-05-20T03:07:32.40432Z","end":"2026-05-20T03:07:32.722588Z","steps":["trace[1682377964] 'agreement among raft nodes before linearized reading' (duration: 318.136513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:32.722627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:32.40431Z","time spent":"318.30936ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-20T03:07:32.728852Z","caller":"traceutil/trace.go:171","msg":"trace[141828324] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"113.328478ms","start":"2026-05-20T03:07:32.61551Z","end":"2026-05-20T03:07:32.728838Z","steps":["trace[141828324] 'process raft request' (duration: 113.015565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:32.728903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.88949ms","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-05-20T03:07:32.728978Z","caller":"traceutil/trace.go:171","msg":"trace[1564368181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:497; }","duration":"109.001776ms","start":"2026-05-20T03:07:32.619959Z","end":"2026-05-20T03:07:32.728961Z","steps":["trace[1564368181] 'agreement among raft nodes before linearized reading' (duration: 108.871649ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:33.037768Z","caller":"traceutil/trace.go:171","msg":"trace[1869701] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"288.936582ms","start":"2026-05-20T03:07:32.748814Z","end":"2026-05-20T03:07:33.03775Z","steps":["trace[1869701] 'process raft request' (duration: 271.551918ms)","trace[1869701] 'compare' (duration: 17.240987ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:07:33.037983Z","caller":"traceutil/trace.go:171","msg":"trace[1928886225] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"287.850304ms","start":"2026-05-20T03:07:32.750125Z","end":"2026-05-20T03:07:33.037976Z","steps":["trace[1928886225] 'process raft request' (duration: 287.587692ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:33.042185Z","caller":"traceutil/trace.go:171","msg":"trace[1538755821] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"288.993273ms","start":"2026-05-20T03:07:32.753126Z","end":"2026-05-20T03:07:33.042119Z","steps":["trace[1538755821] 'process raft request' (duration: 284.793438ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:33.042213Z","caller":"traceutil/trace.go:171","msg":"trace[1801115114] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"284.152938ms","start":"2026-05-20T03:07:32.757973Z","end":"2026-05-20T03:07:33.042126Z","steps":["trace[1801115114] 'process raft request' (duration: 283.95068ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:33.042234Z","caller":"traceutil/trace.go:171","msg":"trace[997840221] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"272.841076ms","start":"2026-05-20T03:07:32.76937Z","end":"2026-05-20T03:07:33.042212Z","steps":["trace[997840221] 'process raft request' (duration: 272.634217ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:33.042268Z","caller":"traceutil/trace.go:171","msg":"trace[2069212911] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:523; }","duration":"284.563467ms","start":"2026-05-20T03:07:32.757687Z","end":"2026-05-20T03:07:33.04225Z","steps":["trace[2069212911] 'read index received' (duration: 262.723607ms)","trace[2069212911] 'applied index is now lower than readState.Index' (duration: 21.83884ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:07:33.042417Z","caller":"traceutil/trace.go:171","msg":"trace[344100794] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"242.393086ms","start":"2026-05-20T03:07:32.80001Z","end":"2026-05-20T03:07:33.042403Z","steps":["trace[344100794] 'process raft request' (duration: 242.066121ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:33.042576Z","caller":"traceutil/trace.go:171","msg":"trace[2090608451] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"234.450972ms","start":"2026-05-20T03:07:32.808114Z","end":"2026-05-20T03:07:33.042565Z","steps":["trace[2090608451] 'process raft request' (duration: 234.029335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:33.04262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.906072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-05-20T03:07:33.042689Z","caller":"traceutil/trace.go:171","msg":"trace[1215579179] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:513; }","duration":"285.006206ms","start":"2026-05-20T03:07:32.757662Z","end":"2026-05-20T03:07:33.042668Z","steps":["trace[1215579179] 'agreement among raft nodes before linearized reading' (duration: 284.64615ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:35.064264Z","caller":"traceutil/trace.go:171","msg":"trace[807246425] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"448.49872ms","start":"2026-05-20T03:07:34.61573Z","end":"2026-05-20T03:07:35.064229Z","steps":["trace[807246425] 'read index received' (duration: 423.775926ms)","trace[807246425] 'applied index is now lower than readState.Index' (duration: 24.721454ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:07:35.06449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.382929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:07:35.06453Z","caller":"traceutil/trace.go:171","msg":"trace[1716390379] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:562; }","duration":"105.478104ms","start":"2026-05-20T03:07:34.959042Z","end":"2026-05-20T03:07:35.06452Z","steps":["trace[1716390379] 'agreement among raft nodes before linearized reading' (duration: 105.37577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:35.064694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.856364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:07:35.064784Z","caller":"traceutil/trace.go:171","msg":"trace[2124471371] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:562; }","duration":"123.98924ms","start":"2026-05-20T03:07:34.940778Z","end":"2026-05-20T03:07:35.064767Z","steps":["trace[2124471371] 'agreement among raft nodes before linearized reading' (duration: 123.817803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:35.064954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.669796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:07:35.064998Z","caller":"traceutil/trace.go:171","msg":"trace[841251207] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:562; }","duration":"106.731919ms","start":"2026-05-20T03:07:34.958256Z","end":"2026-05-20T03:07:35.064988Z","steps":["trace[841251207] 'agreement among raft nodes before linearized reading' (duration: 106.673917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:35.065064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.221059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:07:35.065096Z","caller":"traceutil/trace.go:171","msg":"trace[1949833735] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:562; }","duration":"124.265221ms","start":"2026-05-20T03:07:34.940822Z","end":"2026-05-20T03:07:35.065087Z","steps":["trace[1949833735] 'agreement among raft nodes before linearized reading' (duration: 124.20788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:35.06513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.651806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:07:35.065159Z","caller":"traceutil/trace.go:171","msg":"trace[298872959] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:562; }","duration":"106.690648ms","start":"2026-05-20T03:07:34.95846Z","end":"2026-05-20T03:07:35.065151Z","steps":["trace[298872959] 'agreement among raft nodes before linearized reading' (duration: 106.648895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:35.064789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.976509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:07:35.065221Z","caller":"traceutil/trace.go:171","msg":"trace[1516984889] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:562; }","duration":"124.434669ms","start":"2026-05-20T03:07:34.940778Z","end":"2026-05-20T03:07:35.065212Z","steps":["trace[1516984889] 'agreement among raft nodes before linearized reading' (duration: 123.981879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:35.065662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.898111ms","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-05-20T03:07:35.065738Z","caller":"traceutil/trace.go:171","msg":"trace[680361293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:562; }","duration":"450.019436ms","start":"2026-05-20T03:07:34.615699Z","end":"2026-05-20T03:07:35.065718Z","steps":["trace[680361293] 'agreement among raft nodes before linearized reading' (duration: 448.624676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:35.065771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:34.615683Z","time spent":"450.08004ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T03:07:35.06581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.320197ms","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-05-20T03:07:35.065857Z","caller":"traceutil/trace.go:171","msg":"trace[793916624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:562; }","duration":"324.382689ms","start":"2026-05-20T03:07:34.741463Z","end":"2026-05-20T03:07:35.065846Z","steps":["trace[793916624] 'agreement among raft nodes before linearized reading' (duration: 322.919417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:35.065887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:34.74141Z","time spent":"324.469363ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T03:07:35.065962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.513518ms","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-05-20T03:07:35.066003Z","caller":"traceutil/trace.go:171","msg":"trace[505264710] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:562; }","duration":"305.574942ms","start":"2026-05-20T03:07:34.760416Z","end":"2026-05-20T03:07:35.065991Z","steps":["trace[505264710] 'agreement among raft nodes before linearized reading' (duration: 304.394781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:35.066067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:34.760405Z","time spent":"305.655504ms","remote":"127.0.0.1:55090","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-05-20T03:07:35.06616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.847642ms","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-05-20T03:07:35.066256Z","caller":"traceutil/trace.go:171","msg":"trace[1003367713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"322.964407ms","start":"2026-05-20T03:07:34.743275Z","end":"2026-05-20T03:07:35.06624Z","steps":["trace[1003367713] 'agreement among raft nodes before linearized reading' (duration: 321.548427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:35.066342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:34.743267Z","time spent":"323.056671ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-20T03:07:37.426466Z","caller":"traceutil/trace.go:171","msg":"trace[2091893814] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"384.620848ms","start":"2026-05-20T03:07:37.041823Z","end":"2026-05-20T03:07:37.426443Z","steps":["trace[2091893814] 'process raft request' (duration: 380.025924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:37.426602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:37.041801Z","time spent":"384.731623ms","remote":"127.0.0.1:54924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:07:37.426812Z","caller":"traceutil/trace.go:171","msg":"trace[1521299254] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"352.149885ms","start":"2026-05-20T03:07:37.074648Z","end":"2026-05-20T03:07:37.426798Z","steps":["trace[1521299254] 'read index received' (duration: 346.620181ms)","trace[1521299254] 'applied index is now lower than readState.Index' (duration: 5.528364ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:07:37.427494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.835604ms","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-05-20T03:07:37.427542Z","caller":"traceutil/trace.go:171","msg":"trace[753609051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"352.914558ms","start":"2026-05-20T03:07:37.074616Z","end":"2026-05-20T03:07:37.427531Z","steps":["trace[753609051] 'agreement among raft nodes before linearized reading' (duration: 352.769702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:37.427573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:37.074597Z","time spent":"352.9688ms","remote":"127.0.0.1:55090","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-05-20T03:07:37.427882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.253255ms","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-05-20T03:07:37.427921Z","caller":"traceutil/trace.go:171","msg":"trace[1675305881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"337.333198ms","start":"2026-05-20T03:07:37.090578Z","end":"2026-05-20T03:07:37.427912Z","steps":["trace[1675305881] 'agreement among raft nodes before linearized reading' (duration: 337.242844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:37.427949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:37.090558Z","time spent":"337.385309ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T03:07:37.428106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.843847ms","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-05-20T03:07:37.42814Z","caller":"traceutil/trace.go:171","msg":"trace[851612720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"336.888259ms","start":"2026-05-20T03:07:37.091244Z","end":"2026-05-20T03:07:37.428132Z","steps":["trace[851612720] 'agreement among raft nodes before linearized reading' (duration: 336.811226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:37.428165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:37.091238Z","time spent":"336.92171ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T03:07:37.428312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.083947ms","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-05-20T03:07:37.428344Z","caller":"traceutil/trace.go:171","msg":"trace[207219157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:571; }","duration":"337.130719ms","start":"2026-05-20T03:07:37.091205Z","end":"2026-05-20T03:07:37.428336Z","steps":["trace[207219157] 'agreement among raft nodes before linearized reading' (duration: 337.054226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:37.428369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:37.091196Z","time spent":"337.167871ms","remote":"127.0.0.1:55090","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-05-20T03:07:39.31951Z","caller":"traceutil/trace.go:171","msg":"trace[48800707] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"318.858499ms","start":"2026-05-20T03:07:39.000626Z","end":"2026-05-20T03:07:39.319485Z","steps":["trace[48800707] 'read index received' (duration: 318.054255ms)","trace[48800707] 'applied index is now lower than readState.Index' (duration: 803.424µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:07:39.319625Z","caller":"traceutil/trace.go:171","msg":"trace[673935593] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"380.745883ms","start":"2026-05-20T03:07:38.938827Z","end":"2026-05-20T03:07:39.319573Z","steps":["trace[673935593] 'process raft request' (duration: 380.082386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:39.319691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.350386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-20T03:07:39.319841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:38.938786Z","time spent":"380.93213ms","remote":"127.0.0.1:54924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:07:39.319855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.211374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bh5x4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-20T03:07:39.3199Z","caller":"traceutil/trace.go:171","msg":"trace[757701743] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:585; }","duration":"304.616147ms","start":"2026-05-20T03:07:39.015265Z","end":"2026-05-20T03:07:39.319882Z","steps":["trace[757701743] 'agreement among raft nodes before linearized reading' (duration: 304.360016ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:39.319959Z","caller":"traceutil/trace.go:171","msg":"trace[1924406280] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bh5x4; range_end:; response_count:1; response_revision:585; }","duration":"319.37557ms","start":"2026-05-20T03:07:39.000564Z","end":"2026-05-20T03:07:39.319939Z","steps":["trace[1924406280] 'agreement among raft nodes before linearized reading' (duration: 319.037036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:39.320056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:39.000541Z","time spent":"319.504176ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-bh5x4\" "} {"level":"warn","ts":"2026-05-20T03:07:39.320137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:39.015243Z","time spent":"304.779543ms","remote":"127.0.0.1:54852","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.132\" "} {"level":"info","ts":"2026-05-20T03:07:39.485805Z","caller":"traceutil/trace.go:171","msg":"trace[1105219970] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"150.74998ms","start":"2026-05-20T03:07:39.335017Z","end":"2026-05-20T03:07:39.485767Z","steps":["trace[1105219970] 'process raft request' (duration: 150.413316ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:39.485932Z","caller":"traceutil/trace.go:171","msg":"trace[1298938462] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"104.467718ms","start":"2026-05-20T03:07:39.381424Z","end":"2026-05-20T03:07:39.485892Z","steps":["trace[1298938462] 'process raft request' (duration: 104.145324ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:39.514954Z","caller":"traceutil/trace.go:171","msg":"trace[304109126] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"109.066788ms","start":"2026-05-20T03:07:39.405859Z","end":"2026-05-20T03:07:39.514926Z","steps":["trace[304109126] 'process raft request' (duration: 108.839399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:40.727469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.894137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:07:40.727771Z","caller":"traceutil/trace.go:171","msg":"trace[1588059719] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.207698364s","start":"2026-05-20T03:07:39.520042Z","end":"2026-05-20T03:07:40.72774Z","steps":["trace[1588059719] 'process raft request' (duration: 588.922111ms)","trace[1588059719] 'compare' (duration: 617.48661ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:07:40.727924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:39.520028Z","time spent":"1.207817798s","remote":"127.0.0.1:55090","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-05-20T03:07:40.727914Z","caller":"traceutil/trace.go:171","msg":"trace[631055947] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.207195132s","start":"2026-05-20T03:07:39.520678Z","end":"2026-05-20T03:07:40.727873Z","steps":["trace[631055947] 'process raft request' (duration: 1.206995624s)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:40.727939Z","caller":"traceutil/trace.go:171","msg":"trace[1889716303] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.207769707s","start":"2026-05-20T03:07:39.520132Z","end":"2026-05-20T03:07:40.727901Z","steps":["trace[1889716303] 'process raft request' (duration: 1.207481525s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:40.72807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:39.52067Z","time spent":"1.207316918s","remote":"127.0.0.1:55090","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-05-20T03:07:40.728195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:39.520103Z","time spent":"1.207985875s","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:07:40.728274Z","caller":"traceutil/trace.go:171","msg":"trace[1425964428] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"795.528751ms","start":"2026-05-20T03:07:39.932722Z","end":"2026-05-20T03:07:40.728251Z","steps":["trace[1425964428] 'process raft request' (duration: 795.148185ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:40.728225Z","caller":"traceutil/trace.go:171","msg":"trace[1677702377] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.205893308s","start":"2026-05-20T03:07:39.522278Z","end":"2026-05-20T03:07:40.728172Z","steps":["trace[1677702377] 'process raft request' (duration: 1.205412189s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:07:40.72834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:39.932689Z","time spent":"795.620715ms","remote":"127.0.0.1:54924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:07:40.728504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:07:39.522255Z","time spent":"1.206087597s","remote":"127.0.0.1:55090","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-05-20T03:07:41.279488Z","caller":"traceutil/trace.go:171","msg":"trace[244531106] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"227.945661ms","start":"2026-05-20T03:07:41.05152Z","end":"2026-05-20T03:07:41.279466Z","steps":["trace[244531106] 'process raft request' (duration: 227.784975ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:07:41.387357Z","caller":"traceutil/trace.go:171","msg":"trace[1175881029] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"171.25314ms","start":"2026-05-20T03:07:41.216055Z","end":"2026-05-20T03:07:41.387308Z","steps":["trace[1175881029] 'process raft request' (duration: 160.576355ms)","trace[1175881029] 'compare' (duration: 10.169505ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:07:56.341852Z","caller":"traceutil/trace.go:171","msg":"trace[1081069276] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"225.406977ms","start":"2026-05-20T03:07:56.116001Z","end":"2026-05-20T03:07:56.341408Z","steps":["trace[1081069276] 'process raft request' (duration: 225.230311ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:09.363955Z","caller":"traceutil/trace.go:171","msg":"trace[509031840] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"126.743748ms","start":"2026-05-20T03:08:09.237185Z","end":"2026-05-20T03:08:09.363929Z","steps":["trace[509031840] 'process raft request' (duration: 126.569932ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:15.153942Z","caller":"traceutil/trace.go:171","msg":"trace[142089777] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"130.165929ms","start":"2026-05-20T03:08:15.023723Z","end":"2026-05-20T03:08:15.153889Z","steps":["trace[142089777] 'process raft request' (duration: 129.906912ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:15.154055Z","caller":"traceutil/trace.go:171","msg":"trace[8422799] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"129.659065ms","start":"2026-05-20T03:08:15.02433Z","end":"2026-05-20T03:08:15.153989Z","steps":["trace[8422799] 'process raft request' (duration: 129.47969ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:15.155089Z","caller":"traceutil/trace.go:171","msg":"trace[2025518074] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"124.962609ms","start":"2026-05-20T03:08:15.030102Z","end":"2026-05-20T03:08:15.155064Z","steps":["trace[2025518074] 'process raft request' (duration: 123.756362ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:15.155504Z","caller":"traceutil/trace.go:171","msg":"trace[519652176] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1103; }","duration":"131.023796ms","start":"2026-05-20T03:08:15.024464Z","end":"2026-05-20T03:08:15.155014Z","steps":["trace[519652176] 'read index received' (duration: 10.952098ms)","trace[519652176] 'applied index is now lower than readState.Index' (duration: 119.597474ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:08:15.155709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.220273ms","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-05-20T03:08:15.155782Z","caller":"traceutil/trace.go:171","msg":"trace[2049958719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1079; }","duration":"131.355357ms","start":"2026-05-20T03:08:15.024405Z","end":"2026-05-20T03:08:15.155761Z","steps":["trace[2049958719] 'agreement among raft nodes before linearized reading' (duration: 130.990155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:27.421434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.85027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-20T03:08:27.421544Z","caller":"traceutil/trace.go:171","msg":"trace[1029977370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1159; }","duration":"172.979914ms","start":"2026-05-20T03:08:27.248539Z","end":"2026-05-20T03:08:27.421519Z","steps":["trace[1029977370] 'range keys from in-memory index tree' (duration: 172.601263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:27.422051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.266224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-20T03:08:27.422124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.049627ms","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-05-20T03:08:27.422138Z","caller":"traceutil/trace.go:171","msg":"trace[1686943438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1159; }","duration":"101.412338ms","start":"2026-05-20T03:08:27.320706Z","end":"2026-05-20T03:08:27.422118Z","steps":["trace[1686943438] 'range keys from in-memory index tree' (duration: 101.086589ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:27.422199Z","caller":"traceutil/trace.go:171","msg":"trace[1336081719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"151.137279ms","start":"2026-05-20T03:08:27.271024Z","end":"2026-05-20T03:08:27.422161Z","steps":["trace[1336081719] 'range keys from in-memory index tree' (duration: 150.928073ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:28.088976Z","caller":"traceutil/trace.go:171","msg":"trace[1160995290] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"581.130974ms","start":"2026-05-20T03:08:27.507818Z","end":"2026-05-20T03:08:28.088949Z","steps":["trace[1160995290] 'process raft request' (duration: 580.963129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:28.089153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:27.507797Z","time spent":"581.270869ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:08:42.788991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.384708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:08:42.789088Z","caller":"traceutil/trace.go:171","msg":"trace[399607322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"348.541441ms","start":"2026-05-20T03:08:42.440525Z","end":"2026-05-20T03:08:42.789067Z","steps":["trace[399607322] 'range keys from in-memory index tree' (duration: 348.259615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:42.789135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:42.440505Z","time spent":"348.614163ms","remote":"127.0.0.1:54846","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-05-20T03:08:43.483023Z","caller":"traceutil/trace.go:171","msg":"trace[1122328111] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"154.126712ms","start":"2026-05-20T03:08:43.328869Z","end":"2026-05-20T03:08:43.482996Z","steps":["trace[1122328111] 'process raft request' (duration: 153.983609ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:43.514324Z","caller":"traceutil/trace.go:171","msg":"trace[997521411] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"168.281531ms","start":"2026-05-20T03:08:43.346011Z","end":"2026-05-20T03:08:43.514292Z","steps":["trace[997521411] 'process raft request' (duration: 155.333383ms)","trace[997521411] 'compare' (duration: 12.837995ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:08:45.400495Z","caller":"traceutil/trace.go:171","msg":"trace[662232039] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"117.693711ms","start":"2026-05-20T03:08:45.282775Z","end":"2026-05-20T03:08:45.400469Z","steps":["trace[662232039] 'process raft request' (duration: 109.818118ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:46.294671Z","caller":"traceutil/trace.go:171","msg":"trace[614551743] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"135.007398ms","start":"2026-05-20T03:08:46.159611Z","end":"2026-05-20T03:08:46.294619Z","steps":["trace[614551743] 'process raft request' (duration: 90.604951ms)","trace[614551743] 'compare' (duration: 44.087269ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:08:46.294758Z","caller":"traceutil/trace.go:171","msg":"trace[477504629] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"122.384685ms","start":"2026-05-20T03:08:46.172344Z","end":"2026-05-20T03:08:46.294729Z","steps":["trace[477504629] 'process raft request' (duration: 122.215421ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:46.294663Z","caller":"traceutil/trace.go:171","msg":"trace[149653331] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"122.489027ms","start":"2026-05-20T03:08:46.172141Z","end":"2026-05-20T03:08:46.29463Z","steps":["trace[149653331] 'process raft request' (duration: 122.360854ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:46.294956Z","caller":"traceutil/trace.go:171","msg":"trace[712138545] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"121.638805ms","start":"2026-05-20T03:08:46.173297Z","end":"2026-05-20T03:08:46.294936Z","steps":["trace[712138545] 'process raft request' (duration: 121.312017ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:08:48.495953Z","caller":"traceutil/trace.go:171","msg":"trace[2070014149] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"134.958403ms","start":"2026-05-20T03:08:48.360962Z","end":"2026-05-20T03:08:48.49592Z","steps":["trace[2070014149] 'process raft request' (duration: 134.725326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:49.247529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.360972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-20T03:08:49.247613Z","caller":"traceutil/trace.go:171","msg":"trace[617610151] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1418; }","duration":"123.475145ms","start":"2026-05-20T03:08:49.124116Z","end":"2026-05-20T03:08:49.247591Z","steps":["trace[617610151] 'range keys from in-memory index tree' (duration: 123.205438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:50.205143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.871995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:08:50.20524Z","caller":"traceutil/trace.go:171","msg":"trace[22895733] linearizableReadLoop","detail":"{readStateIndex:1458; appliedIndex:1457; }","duration":"773.99877ms","start":"2026-05-20T03:08:49.431224Z","end":"2026-05-20T03:08:50.205223Z","steps":["trace[22895733] 'read index received' (duration: 45.692µs)","trace[22895733] 'applied index is now lower than readState.Index' (duration: 773.951768ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:08:50.20548Z","caller":"traceutil/trace.go:171","msg":"trace[481028954] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"866.882693ms","start":"2026-05-20T03:08:49.338574Z","end":"2026-05-20T03:08:50.205456Z","steps":["trace[481028954] 'process raft request' (duration: 77.605258ms)","trace[481028954] 'compare' (duration: 788.396273ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:08:50.205558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:49.338541Z","time spent":"866.983115ms","remote":"127.0.0.1:55090","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-05-20T03:08:50.205803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.586815ms","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-05-20T03:08:50.20584Z","caller":"traceutil/trace.go:171","msg":"trace[412572671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1420; }","duration":"774.622896ms","start":"2026-05-20T03:08:49.431207Z","end":"2026-05-20T03:08:50.20583Z","steps":["trace[412572671] 'agreement among raft nodes before linearized reading' (duration: 774.505953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:50.205868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:49.431151Z","time spent":"774.711287ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-20T03:08:50.316849Z","caller":"traceutil/trace.go:171","msg":"trace[2085949259] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"563.061735ms","start":"2026-05-20T03:08:49.753764Z","end":"2026-05-20T03:08:50.316826Z","steps":["trace[2085949259] 'process raft request' (duration: 555.606597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:50.316992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.782811ms","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-05-20T03:08:50.317031Z","caller":"traceutil/trace.go:171","msg":"trace[2046399589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1422; }","duration":"731.859362ms","start":"2026-05-20T03:08:49.58516Z","end":"2026-05-20T03:08:50.31702Z","steps":["trace[2046399589] 'agreement among raft nodes before linearized reading' (duration: 731.734919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:50.317041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.278952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-20T03:08:50.316991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:49.753743Z","time spent":"563.176188ms","remote":"127.0.0.1:55090","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-05-20T03:08:50.317098Z","caller":"traceutil/trace.go:171","msg":"trace[164866844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1422; }","duration":"640.370085ms","start":"2026-05-20T03:08:49.676719Z","end":"2026-05-20T03:08:50.317089Z","steps":["trace[164866844] 'agreement among raft nodes before linearized reading' (duration: 640.235952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:50.317096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.980414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-20T03:08:50.31707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:49.585136Z","time spent":"731.922895ms","remote":"127.0.0.1:55090","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-05-20T03:08:50.316872Z","caller":"traceutil/trace.go:171","msg":"trace[1842353978] linearizableReadLoop","detail":"{readStateIndex:1459; appliedIndex:1458; }","duration":"111.591521ms","start":"2026-05-20T03:08:50.205266Z","end":"2026-05-20T03:08:50.316857Z","steps":["trace[1842353978] 'read index received' (duration: 104.091872ms)","trace[1842353978] 'applied index is now lower than readState.Index' (duration: 7.498169ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:08:50.317172Z","caller":"traceutil/trace.go:171","msg":"trace[1050102886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1422; }","duration":"659.097117ms","start":"2026-05-20T03:08:49.658055Z","end":"2026-05-20T03:08:50.317152Z","steps":["trace[1050102886] 'agreement among raft nodes before linearized reading' (duration: 658.904432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:50.317206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:49.658035Z","time spent":"659.162489ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-20T03:08:50.316812Z","caller":"traceutil/trace.go:171","msg":"trace[151635385] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"107.013806ms","start":"2026-05-20T03:08:50.20978Z","end":"2026-05-20T03:08:50.316794Z","steps":["trace[151635385] 'process raft request' (duration: 106.980815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:08:50.31713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:08:49.676706Z","time spent":"640.418816ms","remote":"127.0.0.1:55090","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-05-20T03:09:08.581507Z","caller":"traceutil/trace.go:171","msg":"trace[166791756] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"157.510484ms","start":"2026-05-20T03:09:08.423977Z","end":"2026-05-20T03:09:08.581487Z","steps":["trace[166791756] 'process raft request' (duration: 157.415442ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:08.581586Z","caller":"traceutil/trace.go:171","msg":"trace[262770828] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"157.74839ms","start":"2026-05-20T03:09:08.423794Z","end":"2026-05-20T03:09:08.581543Z","steps":["trace[262770828] 'process raft request' (duration: 157.561405ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:08.581515Z","caller":"traceutil/trace.go:171","msg":"trace[931700486] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"158.497816ms","start":"2026-05-20T03:09:08.422976Z","end":"2026-05-20T03:09:08.581474Z","steps":["trace[931700486] 'process raft request' (duration: 110.110537ms)","trace[931700486] 'compare' (duration: 48.150563ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:09:08.581821Z","caller":"traceutil/trace.go:171","msg":"trace[2012116409] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"157.090593ms","start":"2026-05-20T03:09:08.424707Z","end":"2026-05-20T03:09:08.581798Z","steps":["trace[2012116409] 'process raft request' (duration: 156.733865ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:08.581829Z","caller":"traceutil/trace.go:171","msg":"trace[454046748] linearizableReadLoop","detail":"{readStateIndex:1789; appliedIndex:1785; }","duration":"121.92772ms","start":"2026-05-20T03:09:08.459854Z","end":"2026-05-20T03:09:08.581782Z","steps":["trace[454046748] 'read index received' (duration: 73.232964ms)","trace[454046748] 'applied index is now lower than readState.Index' (duration: 48.693236ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:09:08.582041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.179227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-20T03:09:08.582092Z","caller":"traceutil/trace.go:171","msg":"trace[949219981] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1724; }","duration":"122.275808ms","start":"2026-05-20T03:09:08.459802Z","end":"2026-05-20T03:09:08.582078Z","steps":["trace[949219981] 'agreement among raft nodes before linearized reading' (duration: 122.069673ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:08.910621Z","caller":"traceutil/trace.go:171","msg":"trace[1699387508] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"102.573672ms","start":"2026-05-20T03:09:08.808014Z","end":"2026-05-20T03:09:08.910588Z","steps":["trace[1699387508] 'process raft request' (duration: 102.314567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:15.530457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.368353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-56e20842-8780-4864-86b0-ec274373f5a0\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-20T03:09:15.530541Z","caller":"traceutil/trace.go:171","msg":"trace[629210598] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-56e20842-8780-4864-86b0-ec274373f5a0; range_end:; response_count:1; response_revision:1826; }","duration":"162.495447ms","start":"2026-05-20T03:09:15.368027Z","end":"2026-05-20T03:09:15.530522Z","steps":["trace[629210598] 'range keys from in-memory index tree' (duration: 162.2106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:15.530723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.357705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-20T03:09:15.530826Z","caller":"traceutil/trace.go:171","msg":"trace[348330166] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1826; }","duration":"426.50596ms","start":"2026-05-20T03:09:15.104294Z","end":"2026-05-20T03:09:15.5308Z","steps":["trace[348330166] 'range keys from in-memory index tree' (duration: 426.117899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:15.530903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:15.104226Z","time spent":"426.664223ms","remote":"127.0.0.1:46276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-20T03:09:15.851135Z","caller":"traceutil/trace.go:171","msg":"trace[1973527360] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"312.674626ms","start":"2026-05-20T03:09:15.538437Z","end":"2026-05-20T03:09:15.851112Z","steps":["trace[1973527360] 'process raft request' (duration: 312.540633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:15.851293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:15.538409Z","time spent":"312.78692ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:09:29.156343Z","caller":"traceutil/trace.go:171","msg":"trace[1388326945] linearizableReadLoop","detail":"{readStateIndex:2012; appliedIndex:2011; }","duration":"126.270177ms","start":"2026-05-20T03:09:29.030048Z","end":"2026-05-20T03:09:29.156318Z","steps":["trace[1388326945] 'read index received' (duration: 126.079762ms)","trace[1388326945] 'applied index is now lower than readState.Index' (duration: 189.195µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:09:29.156546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.470801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-05-20T03:09:29.156597Z","caller":"traceutil/trace.go:171","msg":"trace[556584402] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1942; }","duration":"126.539343ms","start":"2026-05-20T03:09:29.030028Z","end":"2026-05-20T03:09:29.156567Z","steps":["trace[556584402] 'agreement among raft nodes before linearized reading' (duration: 126.39109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:46.927745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.870019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:09:46.928116Z","caller":"traceutil/trace.go:171","msg":"trace[1796605030] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"370.098341ms","start":"2026-05-20T03:09:46.557973Z","end":"2026-05-20T03:09:46.928071Z","steps":["trace[1796605030] 'process raft request' (duration: 226.822384ms)","trace[1796605030] 'compare' (duration: 142.48342ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:09:46.928301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:46.557941Z","time spent":"370.271714ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:09:46.928275Z","caller":"traceutil/trace.go:171","msg":"trace[810105588] linearizableReadLoop","detail":"{readStateIndex:2123; appliedIndex:2122; }","duration":"223.243641ms","start":"2026-05-20T03:09:46.705002Z","end":"2026-05-20T03:09:46.928246Z","steps":["trace[810105588] 'read index received' (duration: 79.808562ms)","trace[810105588] 'applied index is now lower than readState.Index' (duration: 143.432189ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:09:46.928492Z","caller":"traceutil/trace.go:171","msg":"trace[1647854837] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"205.877456ms","start":"2026-05-20T03:09:46.7226Z","end":"2026-05-20T03:09:46.928477Z","steps":["trace[1647854837] 'process raft request' (duration: 205.486397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:46.928605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.576428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-20T03:09:46.928681Z","caller":"traceutil/trace.go:171","msg":"trace[1638581542] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2052; }","duration":"223.70275ms","start":"2026-05-20T03:09:46.704955Z","end":"2026-05-20T03:09:46.928658Z","steps":["trace[1638581542] 'agreement among raft nodes before linearized reading' (duration: 223.471575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:46.928723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.046342ms","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-05-20T03:09:46.928779Z","caller":"traceutil/trace.go:171","msg":"trace[1247992143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2052; }","duration":"164.153233ms","start":"2026-05-20T03:09:46.76461Z","end":"2026-05-20T03:09:46.928764Z","steps":["trace[1247992143] 'agreement among raft nodes before linearized reading' (duration: 164.041691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:46.928822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.111403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-20T03:09:46.928742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.573275ms","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-05-20T03:09:46.928626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.924054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-20T03:09:46.928884Z","caller":"traceutil/trace.go:171","msg":"trace[592809082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2052; }","duration":"147.763779ms","start":"2026-05-20T03:09:46.781092Z","end":"2026-05-20T03:09:46.928856Z","steps":["trace[592809082] 'agreement among raft nodes before linearized reading' (duration: 147.546604ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:46.928938Z","caller":"traceutil/trace.go:171","msg":"trace[568194871] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2052; }","duration":"176.264522ms","start":"2026-05-20T03:09:46.752655Z","end":"2026-05-20T03:09:46.92892Z","steps":["trace[568194871] 'agreement among raft nodes before linearized reading' (duration: 175.869183ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:46.928972Z","caller":"traceutil/trace.go:171","msg":"trace[1445244098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2052; }","duration":"141.139784ms","start":"2026-05-20T03:09:46.787702Z","end":"2026-05-20T03:09:46.928842Z","steps":["trace[1445244098] 'agreement among raft nodes before linearized reading' (duration: 141.055782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:46.928938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.104024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-20T03:09:46.929028Z","caller":"traceutil/trace.go:171","msg":"trace[1391445879] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2052; }","duration":"143.195625ms","start":"2026-05-20T03:09:46.785815Z","end":"2026-05-20T03:09:46.929011Z","steps":["trace[1391445879] 'agreement among raft nodes before linearized reading' (duration: 143.005251ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:46.928307Z","caller":"traceutil/trace.go:171","msg":"trace[152459129] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"132.453626ms","start":"2026-05-20T03:09:46.795826Z","end":"2026-05-20T03:09:46.92828Z","steps":["trace[152459129] 'process raft request' (duration: 132.373395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:46.929134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.591442ms","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-05-20T03:09:46.929248Z","caller":"traceutil/trace.go:171","msg":"trace[1229996002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2052; }","duration":"164.8992ms","start":"2026-05-20T03:09:46.764333Z","end":"2026-05-20T03:09:46.929232Z","steps":["trace[1229996002] 'agreement among raft nodes before linearized reading' (duration: 164.469071ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:47.060138Z","caller":"traceutil/trace.go:171","msg":"trace[1962237880] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"126.627974ms","start":"2026-05-20T03:09:46.933481Z","end":"2026-05-20T03:09:47.060109Z","steps":["trace[1962237880] 'process raft request' (duration: 125.677615ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:47.060199Z","caller":"traceutil/trace.go:171","msg":"trace[1770576340] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"125.97549ms","start":"2026-05-20T03:09:46.934194Z","end":"2026-05-20T03:09:47.060169Z","steps":["trace[1770576340] 'process raft request' (duration: 125.894739ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:47.060222Z","caller":"traceutil/trace.go:171","msg":"trace[928624724] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"126.720936ms","start":"2026-05-20T03:09:46.933481Z","end":"2026-05-20T03:09:47.060202Z","steps":["trace[928624724] 'process raft request' (duration: 126.557243ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:47.06053Z","caller":"traceutil/trace.go:171","msg":"trace[926492553] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"125.43479ms","start":"2026-05-20T03:09:46.935079Z","end":"2026-05-20T03:09:47.060513Z","steps":["trace[926492553] 'process raft request' (duration: 125.049252ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:47.06068Z","caller":"traceutil/trace.go:171","msg":"trace[1832449626] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"125.065602ms","start":"2026-05-20T03:09:46.935434Z","end":"2026-05-20T03:09:47.0605Z","steps":["trace[1832449626] 'process raft request' (duration: 124.996101ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:47.359041Z","caller":"traceutil/trace.go:171","msg":"trace[246279263] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"101.831594ms","start":"2026-05-20T03:09:47.257183Z","end":"2026-05-20T03:09:47.359015Z","steps":["trace[246279263] 'process raft request' (duration: 101.684762ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:49.190705Z","caller":"traceutil/trace.go:171","msg":"trace[400104867] linearizableReadLoop","detail":"{readStateIndex:2134; appliedIndex:2133; }","duration":"380.378622ms","start":"2026-05-20T03:09:48.810301Z","end":"2026-05-20T03:09:49.190679Z","steps":["trace[400104867] 'read index received' (duration: 380.27613ms)","trace[400104867] 'applied index is now lower than readState.Index' (duration: 101.382µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:09:49.190979Z","caller":"traceutil/trace.go:171","msg":"trace[885216752] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"419.691222ms","start":"2026-05-20T03:09:48.771274Z","end":"2026-05-20T03:09:49.190965Z","steps":["trace[885216752] 'process raft request' (duration: 419.279443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:49.191097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:48.771265Z","time spent":"419.751462ms","remote":"127.0.0.1:54902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:09:49.191377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.070327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T03:09:49.191419Z","caller":"traceutil/trace.go:171","msg":"trace[346533953] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2061; }","duration":"381.143427ms","start":"2026-05-20T03:09:48.810266Z","end":"2026-05-20T03:09:49.191409Z","steps":["trace[346533953] 'agreement among raft nodes before linearized reading' (duration: 381.006355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:49.191448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:48.810245Z","time spent":"381.195328ms","remote":"127.0.0.1:55194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":90,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-05-20T03:09:49.368891Z","caller":"traceutil/trace.go:171","msg":"trace[1305924678] linearizableReadLoop","detail":"{readStateIndex:2136; appliedIndex:2134; }","duration":"178.091391ms","start":"2026-05-20T03:09:49.190775Z","end":"2026-05-20T03:09:49.368867Z","steps":["trace[1305924678] 'read index received' (duration: 147.858777ms)","trace[1305924678] 'applied index is now lower than readState.Index' (duration: 30.231744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:09:49.369096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.494173ms","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-05-20T03:09:49.369175Z","caller":"traceutil/trace.go:171","msg":"trace[1542527135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2063; }","duration":"303.587336ms","start":"2026-05-20T03:09:49.065568Z","end":"2026-05-20T03:09:49.369155Z","steps":["trace[1542527135] 'agreement among raft nodes before linearized reading' (duration: 303.370791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:49.369224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:49.065564Z","time spent":"303.651377ms","remote":"127.0.0.1:55090","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-05-20T03:09:49.369274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.051315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-20T03:09:49.369305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.68877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T03:09:49.369328Z","caller":"traceutil/trace.go:171","msg":"trace[1974693420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2063; }","duration":"305.116717ms","start":"2026-05-20T03:09:49.064194Z","end":"2026-05-20T03:09:49.36931Z","steps":["trace[1974693420] 'agreement among raft nodes before linearized reading' (duration: 305.011924ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:49.369364Z","caller":"traceutil/trace.go:171","msg":"trace[772708372] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2063; }","duration":"444.783482ms","start":"2026-05-20T03:09:48.924568Z","end":"2026-05-20T03:09:49.369352Z","steps":["trace[772708372] 'agreement among raft nodes before linearized reading' (duration: 444.68069ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:49.369433Z","caller":"traceutil/trace.go:171","msg":"trace[257173607] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"434.847121ms","start":"2026-05-20T03:09:48.934569Z","end":"2026-05-20T03:09:49.369416Z","steps":["trace[257173607] 'process raft request' (duration: 404.061576ms)","trace[257173607] 'compare' (duration: 30.030549ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:09:49.369371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:49.064179Z","time spent":"305.183678ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T03:09:49.369506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.17532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T03:09:49.369539Z","caller":"traceutil/trace.go:171","msg":"trace[108373068] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:2063; }","duration":"346.24167ms","start":"2026-05-20T03:09:49.023289Z","end":"2026-05-20T03:09:49.369531Z","steps":["trace[108373068] 'agreement among raft nodes before linearized reading' (duration: 346.171408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:49.369551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:48.934551Z","time spent":"434.925832ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:09:49.369566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:49.023272Z","time spent":"346.28871ms","remote":"127.0.0.1:54852","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.132\" "} {"level":"info","ts":"2026-05-20T03:09:49.369709Z","caller":"traceutil/trace.go:171","msg":"trace[1349405690] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"435.033665ms","start":"2026-05-20T03:09:48.934661Z","end":"2026-05-20T03:09:49.369694Z","steps":["trace[1349405690] 'process raft request' (duration: 434.142446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:49.369776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:48.934653Z","time spent":"435.091425ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:09:49.369219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.660627ms","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-05-20T03:09:49.369871Z","caller":"traceutil/trace.go:171","msg":"trace[5925897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2063; }","duration":"304.363001ms","start":"2026-05-20T03:09:49.065497Z","end":"2026-05-20T03:09:49.36986Z","steps":["trace[5925897] 'agreement among raft nodes before linearized reading' (duration: 303.504343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:49.369912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:49.065489Z","time spent":"304.413362ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T03:09:49.369932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.33382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-20T03:09:49.370006Z","caller":"traceutil/trace.go:171","msg":"trace[812983221] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2063; }","duration":"304.409351ms","start":"2026-05-20T03:09:49.065584Z","end":"2026-05-20T03:09:49.369993Z","steps":["trace[812983221] 'agreement among raft nodes before linearized reading' (duration: 304.281539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:49.370043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:49.065581Z","time spent":"304.454403ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-20T03:09:49.370127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.907651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-20T03:09:49.369442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.519665ms","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-05-20T03:09:49.370157Z","caller":"traceutil/trace.go:171","msg":"trace[730962690] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2063; }","duration":"224.974382ms","start":"2026-05-20T03:09:49.145175Z","end":"2026-05-20T03:09:49.370149Z","steps":["trace[730962690] 'agreement among raft nodes before linearized reading' (duration: 224.92799ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:49.37017Z","caller":"traceutil/trace.go:171","msg":"trace[206030764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2063; }","duration":"344.24451ms","start":"2026-05-20T03:09:49.025916Z","end":"2026-05-20T03:09:49.370161Z","steps":["trace[206030764] 'agreement among raft nodes before linearized reading' (duration: 343.476374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:49.370202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:49.025882Z","time spent":"344.312621ms","remote":"127.0.0.1:55090","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-05-20T03:09:49.369161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.603532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-20T03:09:49.370286Z","caller":"traceutil/trace.go:171","msg":"trace[718050904] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2063; }","duration":"425.792077ms","start":"2026-05-20T03:09:48.944485Z","end":"2026-05-20T03:09:49.370277Z","steps":["trace[718050904] 'agreement among raft nodes before linearized reading' (duration: 424.541171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:49.370314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:48.944474Z","time spent":"425.832098ms","remote":"127.0.0.1:46276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-20T03:09:49.369434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:48.924548Z","time spent":"444.852475ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-05-20T03:09:49.516601Z","caller":"traceutil/trace.go:171","msg":"trace[722568674] linearizableReadLoop","detail":"{readStateIndex:2144; appliedIndex:2137; }","duration":"120.488453ms","start":"2026-05-20T03:09:49.396089Z","end":"2026-05-20T03:09:49.516578Z","steps":["trace[722568674] 'read index received' (duration: 77.148344ms)","trace[722568674] 'applied index is now lower than readState.Index' (duration: 43.339019ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:09:49.516799Z","caller":"traceutil/trace.go:171","msg":"trace[1561979457] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"138.515389ms","start":"2026-05-20T03:09:49.378269Z","end":"2026-05-20T03:09:49.516784Z","steps":["trace[1561979457] 'process raft request' (duration: 138.07367ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:49.516606Z","caller":"traceutil/trace.go:171","msg":"trace[1740381350] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"138.338514ms","start":"2026-05-20T03:09:49.37822Z","end":"2026-05-20T03:09:49.516559Z","steps":["trace[1740381350] 'process raft request' (duration: 137.969337ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:49.517617Z","caller":"traceutil/trace.go:171","msg":"trace[745729487] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"139.272193ms","start":"2026-05-20T03:09:49.378322Z","end":"2026-05-20T03:09:49.517595Z","steps":["trace[745729487] 'process raft request' (duration: 138.13697ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:49.517812Z","caller":"traceutil/trace.go:171","msg":"trace[1922743891] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"139.464258ms","start":"2026-05-20T03:09:49.378293Z","end":"2026-05-20T03:09:49.517757Z","steps":["trace[1922743891] 'process raft request' (duration: 138.084911ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:49.518227Z","caller":"traceutil/trace.go:171","msg":"trace[492368510] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"139.885846ms","start":"2026-05-20T03:09:49.378325Z","end":"2026-05-20T03:09:49.518211Z","steps":["trace[492368510] 'process raft request' (duration: 138.175081ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:49.518394Z","caller":"traceutil/trace.go:171","msg":"trace[1969450509] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"139.845095ms","start":"2026-05-20T03:09:49.378537Z","end":"2026-05-20T03:09:49.518382Z","steps":["trace[1969450509] 'process raft request' (duration: 138.009298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:49.517169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.058934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-05-20T03:09:49.518895Z","caller":"traceutil/trace.go:171","msg":"trace[551980039] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2070; }","duration":"122.961363ms","start":"2026-05-20T03:09:49.395915Z","end":"2026-05-20T03:09:49.518876Z","steps":["trace[551980039] 'agreement among raft nodes before linearized reading' (duration: 121.099676ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:49.871894Z","caller":"traceutil/trace.go:171","msg":"trace[464707509] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"188.769628ms","start":"2026-05-20T03:09:49.683106Z","end":"2026-05-20T03:09:49.871875Z","steps":["trace[464707509] 'process raft request' (duration: 188.595954ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:50.706328Z","caller":"traceutil/trace.go:171","msg":"trace[1669118414] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"166.513558ms","start":"2026-05-20T03:09:50.53979Z","end":"2026-05-20T03:09:50.706304Z","steps":["trace[1669118414] 'process raft request' (duration: 166.379096ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:51.528634Z","caller":"traceutil/trace.go:171","msg":"trace[1287868234] linearizableReadLoop","detail":"{readStateIndex:2149; appliedIndex:2147; }","duration":"143.579257ms","start":"2026-05-20T03:09:51.385042Z","end":"2026-05-20T03:09:51.528621Z","steps":["trace[1287868234] 'read index received' (duration: 66.009202ms)","trace[1287868234] 'applied index is now lower than readState.Index' (duration: 77.569365ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:09:51.528725Z","caller":"traceutil/trace.go:171","msg":"trace[1028060404] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"149.885524ms","start":"2026-05-20T03:09:51.378829Z","end":"2026-05-20T03:09:51.528715Z","steps":["trace[1028060404] 'process raft request' (duration: 149.725581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:51.52893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.874173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-20T03:09:51.528958Z","caller":"traceutil/trace.go:171","msg":"trace[1204661914] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2075; }","duration":"143.932635ms","start":"2026-05-20T03:09:51.385018Z","end":"2026-05-20T03:09:51.528951Z","steps":["trace[1204661914] 'agreement among raft nodes before linearized reading' (duration: 143.811321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:51.529169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.946752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-20T03:09:51.529194Z","caller":"traceutil/trace.go:171","msg":"trace[222853143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2075; }","duration":"132.994344ms","start":"2026-05-20T03:09:51.396194Z","end":"2026-05-20T03:09:51.529188Z","steps":["trace[222853143] 'agreement among raft nodes before linearized reading' (duration: 132.927282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:44.612632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.61252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:10:44.612722Z","caller":"traceutil/trace.go:171","msg":"trace[986542577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2411; }","duration":"175.742563ms","start":"2026-05-20T03:10:44.43696Z","end":"2026-05-20T03:10:44.612702Z","steps":["trace[986542577] 'range keys from in-memory index tree' (duration: 175.541729ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:53.165424Z","caller":"traceutil/trace.go:171","msg":"trace[1101793207] transaction","detail":"{read_only:false; response_revision:2507; number_of_response:1; }","duration":"167.067284ms","start":"2026-05-20T03:10:52.99833Z","end":"2026-05-20T03:10:53.165397Z","steps":["trace[1101793207] 'process raft request' (duration: 166.916951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:53.626008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.237794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:10:53.62609Z","caller":"traceutil/trace.go:171","msg":"trace[451424479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2507; }","duration":"186.372227ms","start":"2026-05-20T03:10:53.439698Z","end":"2026-05-20T03:10:53.62607Z","steps":["trace[451424479] 'range keys from in-memory index tree' (duration: 186.138772ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:26.178898Z","caller":"traceutil/trace.go:171","msg":"trace[1356098806] transaction","detail":"{read_only:false; response_revision:2687; number_of_response:1; }","duration":"306.330738ms","start":"2026-05-20T03:11:25.872531Z","end":"2026-05-20T03:11:26.178862Z","steps":["trace[1356098806] 'process raft request' (duration: 256.391178ms)","trace[1356098806] 'compare' (duration: 49.796137ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:11:26.179323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:25.872511Z","time spent":"306.608052ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:11:26.190278Z","caller":"traceutil/trace.go:171","msg":"trace[1843719159] linearizableReadLoop","detail":"{readStateIndex:2785; appliedIndex:2783; }","duration":"168.46705ms","start":"2026-05-20T03:11:26.021788Z","end":"2026-05-20T03:11:26.190255Z","steps":["trace[1843719159] 'read index received' (duration: 107.19706ms)","trace[1843719159] 'applied index is now lower than readState.Index' (duration: 61.26905ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:26.19043Z","caller":"traceutil/trace.go:171","msg":"trace[1857563647] transaction","detail":"{read_only:false; response_revision:2688; number_of_response:1; }","duration":"211.286843ms","start":"2026-05-20T03:11:25.979085Z","end":"2026-05-20T03:11:26.190371Z","steps":["trace[1857563647] 'process raft request' (duration: 211.079839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:26.191003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.874508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-20T03:11:26.191085Z","caller":"traceutil/trace.go:171","msg":"trace[864278524] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2688; }","duration":"169.320296ms","start":"2026-05-20T03:11:26.021739Z","end":"2026-05-20T03:11:26.191059Z","steps":["trace[864278524] 'agreement among raft nodes before linearized reading' (duration: 168.633904ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:26.380375Z","caller":"traceutil/trace.go:171","msg":"trace[1193016673] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"184.319984ms","start":"2026-05-20T03:11:26.196029Z","end":"2026-05-20T03:11:26.380349Z","steps":["trace[1193016673] 'process raft request' (duration: 184.200231ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:26.380546Z","caller":"traceutil/trace.go:171","msg":"trace[145249895] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"184.313152ms","start":"2026-05-20T03:11:26.196203Z","end":"2026-05-20T03:11:26.380516Z","steps":["trace[145249895] 'process raft request' (duration: 184.106178ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:26.380632Z","caller":"traceutil/trace.go:171","msg":"trace[820026500] transaction","detail":"{read_only:false; response_revision:2690; number_of_response:1; }","duration":"184.73335ms","start":"2026-05-20T03:11:26.195888Z","end":"2026-05-20T03:11:26.380621Z","steps":["trace[820026500] 'process raft request' (duration: 184.127119ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:26.381047Z","caller":"traceutil/trace.go:171","msg":"trace[1736594805] transaction","detail":"{read_only:false; response_revision:2693; number_of_response:1; }","duration":"184.710749ms","start":"2026-05-20T03:11:26.196305Z","end":"2026-05-20T03:11:26.381016Z","steps":["trace[1736594805] 'process raft request' (duration: 184.151789ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:26.38114Z","caller":"traceutil/trace.go:171","msg":"trace[2115859517] linearizableReadLoop","detail":"{readStateIndex:2791; appliedIndex:2786; }","duration":"157.403344ms","start":"2026-05-20T03:11:26.223723Z","end":"2026-05-20T03:11:26.381126Z","steps":["trace[2115859517] 'read index received' (duration: 156.226333ms)","trace[2115859517] 'applied index is now lower than readState.Index' (duration: 1.175971ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:26.381183Z","caller":"traceutil/trace.go:171","msg":"trace[1874269564] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"184.850581ms","start":"2026-05-20T03:11:26.196318Z","end":"2026-05-20T03:11:26.381168Z","steps":["trace[1874269564] 'process raft request' (duration: 184.607787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:26.38123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.529826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:11:26.381264Z","caller":"traceutil/trace.go:171","msg":"trace[1249598303] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2694; }","duration":"157.625557ms","start":"2026-05-20T03:11:26.223628Z","end":"2026-05-20T03:11:26.381253Z","steps":["trace[1249598303] 'agreement among raft nodes before linearized reading' (duration: 157.547996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:26.917723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.195948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b1276a952b0474\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-20T03:11:26.917812Z","caller":"traceutil/trace.go:171","msg":"trace[321890862] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b1276a952b0474; range_end:; response_count:1; response_revision:2694; }","duration":"404.334111ms","start":"2026-05-20T03:11:26.513456Z","end":"2026-05-20T03:11:26.91779Z","steps":["trace[321890862] 'range keys from in-memory index tree' (duration: 403.975445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:26.918278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:26.513432Z","time spent":"404.82841ms","remote":"127.0.0.1:54902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b1276a952b0474\" "} {"level":"info","ts":"2026-05-20T03:11:32.264323Z","caller":"traceutil/trace.go:171","msg":"trace[1620972638] transaction","detail":"{read_only:false; response_revision:2719; number_of_response:1; }","duration":"129.536269ms","start":"2026-05-20T03:11:32.13476Z","end":"2026-05-20T03:11:32.264296Z","steps":["trace[1620972638] 'process raft request' (duration: 129.227854ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:14:03.02807Z","caller":"traceutil/trace.go:171","msg":"trace[209631340] transaction","detail":"{read_only:false; response_revision:3587; number_of_response:1; }","duration":"102.038244ms","start":"2026-05-20T03:14:02.926009Z","end":"2026-05-20T03:14:03.028047Z","steps":["trace[209631340] 'process raft request' (duration: 101.813079ms)"],"step_count":1}