{"level":"warn","ts":"2026-05-19T02:57:48.922576Z","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-19T02:57:48.922796Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-19T02:57:48.922877Z","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-19T02:57:48.922886Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-05-19T02:57:48.922911Z","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-19T02:57:48.923637Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-05-19T02:57:48.923726Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-19T02:57:48.927624Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.680501ms"} {"level":"info","ts":"2026-05-19T02:57:48.933816Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-05-19T02:57:48.933884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T02:57:48.933912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-05-19T02:57:48.933925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T02:57:48.933941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-05-19T02:57:48.933977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-05-19T02:57:48.941847Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T02:57:48.943139Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T02:57:48.944122Z","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-19T02:57:48.946155Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T02:57:48.947133Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T02:57:48.947282Z","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-19T02:57:48.947345Z","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-19T02:57:48.947362Z","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-19T02:57:48.94772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-05-19T02:57:48.947882Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-05-19T02:57:48.952746Z","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-19T02:57:48.953007Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T02:57:48.953054Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T02:57:48.952992Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-05-19T02:57:48.953182Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-05-19T02:57:49.834331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T02:57:49.834421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T02:57:49.834446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-05-19T02:57:49.834468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-05-19T02:57:49.834479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-05-19T02:57:49.834495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-05-19T02:57:49.834513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-05-19T02:57:49.836273Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T02:57:49.837356Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T02:57:49.837353Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T02:57:49.837572Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T02:57:49.83769Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T02:57:49.837714Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T02:57:49.837678Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T02:57:49.83783Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T02:57:49.837897Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T02:57:49.849288Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T02:57:49.850193Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-05-19T02:58:19.8252Z","caller":"traceutil/trace.go:171","msg":"trace[741143782] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"131.420403ms","start":"2026-05-19T02:58:19.693752Z","end":"2026-05-19T02:58:19.825172Z","steps":["trace[741143782] 'process raft request' (duration: 108.365ms)","trace[741143782] 'compare' (duration: 22.936591ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:58:19.832968Z","caller":"traceutil/trace.go:171","msg":"trace[1484906020] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"132.800291ms","start":"2026-05-19T02:58:19.700134Z","end":"2026-05-19T02:58:19.832934Z","steps":["trace[1484906020] 'process raft request' (duration: 132.726239ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:19.833061Z","caller":"traceutil/trace.go:171","msg":"trace[654474412] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"137.967931ms","start":"2026-05-19T02:58:19.695046Z","end":"2026-05-19T02:58:19.833014Z","steps":["trace[654474412] 'process raft request' (duration: 137.263658ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:22.611317Z","caller":"traceutil/trace.go:171","msg":"trace[1057771925] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"112.191276ms","start":"2026-05-19T02:58:22.499094Z","end":"2026-05-19T02:58:22.611285Z","steps":["trace[1057771925] 'read index received' (duration: 110.132405ms)","trace[1057771925] 'applied index is now lower than readState.Index' (duration: 2.057611ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:58:22.611389Z","caller":"traceutil/trace.go:171","msg":"trace[418493146] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"115.94239ms","start":"2026-05-19T02:58:22.495407Z","end":"2026-05-19T02:58:22.611349Z","steps":["trace[418493146] 'process raft request' (duration: 114.009242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:22.611538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.405321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T02:58:22.611588Z","caller":"traceutil/trace.go:171","msg":"trace[1217133034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"112.541722ms","start":"2026-05-19T02:58:22.499039Z","end":"2026-05-19T02:58:22.611581Z","steps":["trace[1217133034] 'agreement among raft nodes before linearized reading' (duration: 112.360559ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:25.214028Z","caller":"traceutil/trace.go:171","msg":"trace[1303493261] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"180.904409ms","start":"2026-05-19T02:58:25.033095Z","end":"2026-05-19T02:58:25.213999Z","steps":["trace[1303493261] 'process raft request' (duration: 180.715116ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:25.21484Z","caller":"traceutil/trace.go:171","msg":"trace[917509919] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"181.351457ms","start":"2026-05-19T02:58:25.033429Z","end":"2026-05-19T02:58:25.21478Z","steps":["trace[917509919] 'process raft request' (duration: 181.032552ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:29.854943Z","caller":"traceutil/trace.go:171","msg":"trace[15928307] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"112.504575ms","start":"2026-05-19T02:58:29.742395Z","end":"2026-05-19T02:58:29.8549Z","steps":["trace[15928307] 'read index received' (duration: 112.210369ms)","trace[15928307] 'applied index is now lower than readState.Index' (duration: 292.756µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:58:29.855739Z","caller":"traceutil/trace.go:171","msg":"trace[2039574292] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"159.324701ms","start":"2026-05-19T02:58:29.696399Z","end":"2026-05-19T02:58:29.855724Z","steps":["trace[2039574292] 'process raft request' (duration: 158.217479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:29.856234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.825141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:58:29.856488Z","caller":"traceutil/trace.go:171","msg":"trace[1380795298] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:641; }","duration":"114.113747ms","start":"2026-05-19T02:58:29.742356Z","end":"2026-05-19T02:58:29.85647Z","steps":["trace[1380795298] 'agreement among raft nodes before linearized reading' (duration: 113.793931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:29.856762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.323891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:58:29.856871Z","caller":"traceutil/trace.go:171","msg":"trace[1390989367] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:641; }","duration":"114.505915ms","start":"2026-05-19T02:58:29.742348Z","end":"2026-05-19T02:58:29.856854Z","steps":["trace[1390989367] 'agreement among raft nodes before linearized reading' (duration: 114.293781ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:30.230082Z","caller":"traceutil/trace.go:171","msg":"trace[1549495958] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"146.428399ms","start":"2026-05-19T02:58:30.083624Z","end":"2026-05-19T02:58:30.230053Z","steps":["trace[1549495958] 'process raft request' (duration: 146.226084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:30.819609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.962641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-19T02:58:30.8197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.019611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:58:30.819768Z","caller":"traceutil/trace.go:171","msg":"trace[417723044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"482.139044ms","start":"2026-05-19T02:58:30.337554Z","end":"2026-05-19T02:58:30.819693Z","steps":["trace[417723044] 'range keys from in-memory index tree' (duration: 481.721016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:30.819741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.440784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-19T02:58:30.819831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:30.337507Z","time spent":"482.312658ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T02:58:30.819967Z","caller":"traceutil/trace.go:171","msg":"trace[863851137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:644; }","duration":"131.736112ms","start":"2026-05-19T02:58:30.688178Z","end":"2026-05-19T02:58:30.819914Z","steps":["trace[863851137] 'range keys from in-memory index tree' (duration: 131.152448ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:30.820002Z","caller":"traceutil/trace.go:171","msg":"trace[2096685033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"442.378218ms","start":"2026-05-19T02:58:30.377606Z","end":"2026-05-19T02:58:30.819984Z","steps":["trace[2096685033] 'range keys from in-memory index tree' (duration: 441.900408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:30.820048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:30.377577Z","time spent":"442.45841ms","remote":"127.0.0.1:55344","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-19T02:59:40.740793Z","caller":"traceutil/trace.go:171","msg":"trace[1157379668] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"146.517561ms","start":"2026-05-19T02:59:40.594222Z","end":"2026-05-19T02:59:40.74074Z","steps":["trace[1157379668] 'process raft request' (duration: 92.724453ms)","trace[1157379668] 'compare' (duration: 53.530391ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:00:04.694377Z","caller":"traceutil/trace.go:171","msg":"trace[1650067223] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"107.753208ms","start":"2026-05-19T03:00:04.586571Z","end":"2026-05-19T03:00:04.694324Z","steps":["trace[1650067223] 'process raft request' (duration: 49.462738ms)","trace[1650067223] 'compare' (duration: 57.950702ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:00:04.890939Z","caller":"traceutil/trace.go:171","msg":"trace[1663302039] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"101.622197ms","start":"2026-05-19T03:00:04.789296Z","end":"2026-05-19T03:00:04.890918Z","steps":["trace[1663302039] 'process raft request' (duration: 101.516015ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:00:10.012227Z","caller":"traceutil/trace.go:171","msg":"trace[22883269] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"130.224908ms","start":"2026-05-19T03:00:09.881965Z","end":"2026-05-19T03:00:10.01219Z","steps":["trace[22883269] 'process raft request' (duration: 129.771757ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:00:27.033251Z","caller":"traceutil/trace.go:171","msg":"trace[1254840632] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"174.576889ms","start":"2026-05-19T03:00:26.858635Z","end":"2026-05-19T03:00:27.033212Z","steps":["trace[1254840632] 'process raft request' (duration: 174.326353ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:00:27.060568Z","caller":"traceutil/trace.go:171","msg":"trace[2047631980] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2080; }","duration":"183.237419ms","start":"2026-05-19T03:00:26.877292Z","end":"2026-05-19T03:00:27.060529Z","steps":["trace[2047631980] 'read index received' (duration: 155.643234ms)","trace[2047631980] 'applied index is now lower than readState.Index' (duration: 27.592475ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:00:27.060829Z","caller":"traceutil/trace.go:171","msg":"trace[1898265488] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"201.356366ms","start":"2026-05-19T03:00:26.859448Z","end":"2026-05-19T03:00:27.060804Z","steps":["trace[1898265488] 'process raft request' (duration: 200.891205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:00:27.060935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.601548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T03:00:27.061119Z","caller":"traceutil/trace.go:171","msg":"trace[306691000] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2008; }","duration":"183.800021ms","start":"2026-05-19T03:00:26.877288Z","end":"2026-05-19T03:00:27.061088Z","steps":["trace[306691000] 'agreement among raft nodes before linearized reading' (duration: 183.439204ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:00:31.179518Z","caller":"traceutil/trace.go:171","msg":"trace[270811982] linearizableReadLoop","detail":"{readStateIndex:2105; appliedIndex:2104; }","duration":"103.007925ms","start":"2026-05-19T03:00:31.076471Z","end":"2026-05-19T03:00:31.179479Z","steps":["trace[270811982] 'read index received' (duration: 102.642136ms)","trace[270811982] 'applied index is now lower than readState.Index' (duration: 363.739µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:00:31.179653Z","caller":"traceutil/trace.go:171","msg":"trace[862863971] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"128.148416ms","start":"2026-05-19T03:00:31.051448Z","end":"2026-05-19T03:00:31.179596Z","steps":["trace[862863971] 'process raft request' (duration: 127.777637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:00:31.179804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.313962ms","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-19T03:00:31.179877Z","caller":"traceutil/trace.go:171","msg":"trace[820278251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2030; }","duration":"103.451516ms","start":"2026-05-19T03:00:31.076402Z","end":"2026-05-19T03:00:31.179854Z","steps":["trace[820278251] 'agreement among raft nodes before linearized reading' (duration: 103.272371ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:00:31.181343Z","caller":"traceutil/trace.go:171","msg":"trace[1715604249] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"103.097797ms","start":"2026-05-19T03:00:31.078235Z","end":"2026-05-19T03:00:31.181333Z","steps":["trace[1715604249] 'process raft request' (duration: 102.76919ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:00:53.433565Z","caller":"traceutil/trace.go:171","msg":"trace[371798868] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"117.157108ms","start":"2026-05-19T03:00:53.316373Z","end":"2026-05-19T03:00:53.433531Z","steps":["trace[371798868] 'process raft request' (duration: 116.851461ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:00:54.105856Z","caller":"traceutil/trace.go:171","msg":"trace[988753923] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"152.841557ms","start":"2026-05-19T03:00:53.952979Z","end":"2026-05-19T03:00:54.10582Z","steps":["trace[988753923] 'process raft request' (duration: 152.718365ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:00:54.106081Z","caller":"traceutil/trace.go:171","msg":"trace[1640147167] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"153.229866ms","start":"2026-05-19T03:00:53.952769Z","end":"2026-05-19T03:00:54.105999Z","steps":["trace[1640147167] 'process raft request' (duration: 139.150189ms)","trace[1640147167] 'compare' (duration: 13.511355ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:00:54.709317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.040926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:00:54.709448Z","caller":"traceutil/trace.go:171","msg":"trace[1839659822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2161; }","duration":"332.264692ms","start":"2026-05-19T03:00:54.377148Z","end":"2026-05-19T03:00:54.709413Z","steps":["trace[1839659822] 'range keys from in-memory index tree' (duration: 331.892643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:00:54.709521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:00:54.377128Z","time spent":"332.381904ms","remote":"127.0.0.1:55356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T03:00:54.709513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.483795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:00:54.709651Z","caller":"traceutil/trace.go:171","msg":"trace[1151097414] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2161; }","duration":"250.696509ms","start":"2026-05-19T03:00:54.458926Z","end":"2026-05-19T03:00:54.709623Z","steps":["trace[1151097414] 'count revisions from in-memory index tree' (duration: 250.406903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:00:54.710055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.675766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T03:00:54.710186Z","caller":"traceutil/trace.go:171","msg":"trace[133841566] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2161; }","duration":"268.838139ms","start":"2026-05-19T03:00:54.441297Z","end":"2026-05-19T03:00:54.710135Z","steps":["trace[133841566] 'range keys from in-memory index tree' (duration: 268.139253ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:00:54.855788Z","caller":"traceutil/trace.go:171","msg":"trace[1458687761] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"121.317316ms","start":"2026-05-19T03:00:54.734449Z","end":"2026-05-19T03:00:54.855766Z","steps":["trace[1458687761] 'process raft request' (duration: 121.176033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:00:55.080691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.872696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T03:00:55.080787Z","caller":"traceutil/trace.go:171","msg":"trace[2008082384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2162; }","duration":"124.042079ms","start":"2026-05-19T03:00:54.956727Z","end":"2026-05-19T03:00:55.080769Z","steps":["trace[2008082384] 'range keys from in-memory index tree' (duration: 123.152539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:01:51.715675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.744188ms","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-19T03:01:51.715776Z","caller":"traceutil/trace.go:171","msg":"trace[1245657626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2571; }","duration":"141.892392ms","start":"2026-05-19T03:01:51.573866Z","end":"2026-05-19T03:01:51.715758Z","steps":["trace[1245657626] 'range keys from in-memory index tree' (duration: 141.578835ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:24.661519Z","caller":"traceutil/trace.go:171","msg":"trace[941470170] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"136.851736ms","start":"2026-05-19T03:02:24.524649Z","end":"2026-05-19T03:02:24.6615Z","steps":["trace[941470170] 'process raft request' (duration: 136.739053ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:24.666798Z","caller":"traceutil/trace.go:171","msg":"trace[1580936575] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"141.797834ms","start":"2026-05-19T03:02:24.524977Z","end":"2026-05-19T03:02:24.666775Z","steps":["trace[1580936575] 'process raft request' (duration: 141.63505ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:24.66699Z","caller":"traceutil/trace.go:171","msg":"trace[1340432546] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"112.967358ms","start":"2026-05-19T03:02:24.554013Z","end":"2026-05-19T03:02:24.66698Z","steps":["trace[1340432546] 'process raft request' (duration: 112.721972ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:24.667255Z","caller":"traceutil/trace.go:171","msg":"trace[873493144] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"110.509103ms","start":"2026-05-19T03:02:24.556714Z","end":"2026-05-19T03:02:24.667223Z","steps":["trace[873493144] 'process raft request' (duration: 110.232747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:24.930883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.787149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:02:24.930959Z","caller":"traceutil/trace.go:171","msg":"trace[1982538263] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2757; }","duration":"200.920452ms","start":"2026-05-19T03:02:24.730023Z","end":"2026-05-19T03:02:24.930944Z","steps":["trace[1982538263] 'count revisions from in-memory index tree' (duration: 200.717598ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:30.250308Z","caller":"traceutil/trace.go:171","msg":"trace[1207231091] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"137.077749ms","start":"2026-05-19T03:02:30.113208Z","end":"2026-05-19T03:02:30.250286Z","steps":["trace[1207231091] 'process raft request' (duration: 136.965227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:03:26.676741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765478756524204,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T03:03:26.747748Z","caller":"traceutil/trace.go:171","msg":"trace[116655251] transaction","detail":"{read_only:false; response_revision:3080; number_of_response:1; }","duration":"574.8641ms","start":"2026-05-19T03:03:26.172799Z","end":"2026-05-19T03:03:26.747663Z","steps":["trace[116655251] 'process raft request' (duration: 574.620966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:03:26.748532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:03:26.172782Z","time spent":"575.075445ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:03:26.749293Z","caller":"traceutil/trace.go:171","msg":"trace[1855862596] linearizableReadLoop","detail":"{readStateIndex:3195; appliedIndex:3193; }","duration":"572.914696ms","start":"2026-05-19T03:03:26.176349Z","end":"2026-05-19T03:03:26.749263Z","steps":["trace[1855862596] 'read index received' (duration: 571.123226ms)","trace[1855862596] 'applied index is now lower than readState.Index' (duration: 1.78998ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:03:26.74976Z","caller":"traceutil/trace.go:171","msg":"trace[1229259379] transaction","detail":"{read_only:false; response_revision:3081; number_of_response:1; }","duration":"268.715902ms","start":"2026-05-19T03:03:26.481007Z","end":"2026-05-19T03:03:26.749723Z","steps":["trace[1229259379] 'process raft request' (duration: 268.110349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:03:26.749918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.546165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:03:26.749977Z","caller":"traceutil/trace.go:171","msg":"trace[335850732] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:3081; }","duration":"544.644756ms","start":"2026-05-19T03:03:26.205313Z","end":"2026-05-19T03:03:26.749958Z","steps":["trace[335850732] 'agreement among raft nodes before linearized reading' (duration: 544.518983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:03:26.750026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:03:26.205305Z","time spent":"544.706789ms","remote":"127.0.0.1:52788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-05-19T03:03:26.75029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.716607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T03:03:26.750309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.724365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:03:26.750326Z","caller":"traceutil/trace.go:171","msg":"trace[545312395] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:3081; }","duration":"524.765779ms","start":"2026-05-19T03:03:26.225547Z","end":"2026-05-19T03:03:26.750313Z","steps":["trace[545312395] 'agreement among raft nodes before linearized reading' (duration: 524.706167ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:03:26.750352Z","caller":"traceutil/trace.go:171","msg":"trace[112428705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3081; }","duration":"373.793696ms","start":"2026-05-19T03:03:26.376546Z","end":"2026-05-19T03:03:26.750339Z","steps":["trace[112428705] 'agreement among raft nodes before linearized reading' (duration: 373.694274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:03:26.750359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:03:26.22554Z","time spent":"524.81037ms","remote":"127.0.0.1:37974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-19T03:03:26.750384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:03:26.376533Z","time spent":"373.843318ms","remote":"127.0.0.1:55356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T03:03:26.750682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.301078ms","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":"info","ts":"2026-05-19T03:03:26.750716Z","caller":"traceutil/trace.go:171","msg":"trace[1783186194] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3081; }","duration":"574.367479ms","start":"2026-05-19T03:03:26.176336Z","end":"2026-05-19T03:03:26.750704Z","steps":["trace[1783186194] 'agreement among raft nodes before linearized reading' (duration: 574.221046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:03:26.750741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:03:26.17633Z","time spent":"574.40447ms","remote":"127.0.0.1:55664","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-19T03:03:29.544813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.61602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:03:29.544921Z","caller":"traceutil/trace.go:171","msg":"trace[1685324037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3097; }","duration":"167.785994ms","start":"2026-05-19T03:03:29.377112Z","end":"2026-05-19T03:03:29.544898Z","steps":["trace[1685324037] 'range keys from in-memory index tree' (duration: 167.456207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:06:23.815292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.667095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T03:06:23.815859Z","caller":"traceutil/trace.go:171","msg":"trace[1152619153] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"263.441528ms","start":"2026-05-19T03:06:23.552379Z","end":"2026-05-19T03:06:23.81582Z","steps":["trace[1152619153] 'process raft request' (duration: 123.956694ms)","trace[1152619153] 'compare' (duration: 138.201484ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:06:23.816713Z","caller":"traceutil/trace.go:171","msg":"trace[380263402] linearizableReadLoop","detail":"{readStateIndex:4997; appliedIndex:4994; }","duration":"264.117673ms","start":"2026-05-19T03:06:23.55258Z","end":"2026-05-19T03:06:23.816698Z","steps":["trace[380263402] 'read index received' (duration: 19.400369ms)","trace[380263402] 'applied index is now lower than readState.Index' (duration: 244.716334ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:06:23.816707Z","caller":"traceutil/trace.go:171","msg":"trace[262844560] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"264.234595ms","start":"2026-05-19T03:06:23.55245Z","end":"2026-05-19T03:06:23.816685Z","steps":["trace[262844560] 'process raft request' (duration: 262.981617ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:06:23.816736Z","caller":"traceutil/trace.go:171","msg":"trace[2141915067] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"264.04138ms","start":"2026-05-19T03:06:23.552685Z","end":"2026-05-19T03:06:23.816726Z","steps":["trace[2141915067] 'process raft request' (duration: 263.861286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:06:23.816901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.802357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-19T03:06:23.816931Z","caller":"traceutil/trace.go:171","msg":"trace[224531447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4816; }","duration":"166.885629ms","start":"2026-05-19T03:06:23.650037Z","end":"2026-05-19T03:06:23.816922Z","steps":["trace[224531447] 'agreement among raft nodes before linearized reading' (duration: 166.780746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:06:23.816935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.329088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-19T03:06:23.816964Z","caller":"traceutil/trace.go:171","msg":"trace[1803553071] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4816; }","duration":"264.397589ms","start":"2026-05-19T03:06:23.552556Z","end":"2026-05-19T03:06:23.816954Z","steps":["trace[1803553071] 'agreement among raft nodes before linearized reading' (duration: 264.248095ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:07:02.904394Z","caller":"traceutil/trace.go:171","msg":"trace[1377293177] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"168.189518ms","start":"2026-05-19T03:07:02.736178Z","end":"2026-05-19T03:07:02.904368Z","steps":["trace[1377293177] 'process raft request' (duration: 167.966463ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:07:03.037516Z","caller":"traceutil/trace.go:171","msg":"trace[546901907] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"125.542177ms","start":"2026-05-19T03:07:02.911946Z","end":"2026-05-19T03:07:03.037488Z","steps":["trace[546901907] 'process raft request' (duration: 118.448953ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:07:03.038634Z","caller":"traceutil/trace.go:171","msg":"trace[1314882573] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"121.514133ms","start":"2026-05-19T03:07:02.917099Z","end":"2026-05-19T03:07:03.038613Z","steps":["trace[1314882573] 'process raft request' (duration: 121.37549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:26.437586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765478756529696,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:07:26.938013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765478756529696,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:07:27.20998Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.50386875s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T03:07:27.210405Z","caller":"traceutil/trace.go:171","msg":"trace[1920244488] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"1.504365751s","start":"2026-05-19T03:07:25.70601Z","end":"2026-05-19T03:07:27.210376Z","steps":["trace[1920244488] 'process raft request' (duration: 1.504233798s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.210733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:25.705991Z","time spent":"1.504544966s","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:07:27.215135Z","caller":"traceutil/trace.go:171","msg":"trace[159940576] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"1.507424344s","start":"2026-05-19T03:07:25.707685Z","end":"2026-05-19T03:07:27.215109Z","steps":["trace[159940576] 'process raft request' (duration: 1.50683022s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.215447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:25.707669Z","time spent":"1.507622198s","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:07:27.215707Z","caller":"traceutil/trace.go:171","msg":"trace[1889306081] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"1.236922182s","start":"2026-05-19T03:07:25.978758Z","end":"2026-05-19T03:07:27.21568Z","steps":["trace[1889306081] 'process raft request' (duration: 1.236594705s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.215875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:25.978724Z","time spent":"1.237064346s","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12286,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:07:27.216024Z","caller":"traceutil/trace.go:171","msg":"trace[99144248] linearizableReadLoop","detail":"{readStateIndex:5582; appliedIndex:5578; }","duration":"1.278742386s","start":"2026-05-19T03:07:25.937253Z","end":"2026-05-19T03:07:27.215995Z","steps":["trace[99144248] 'read index received' (duration: 1.273163405s)","trace[99144248] 'applied index is now lower than readState.Index' (duration: 5.577601ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:07:27.216416Z","caller":"traceutil/trace.go:171","msg":"trace[1085189230] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"485.654349ms","start":"2026-05-19T03:07:26.73075Z","end":"2026-05-19T03:07:27.216404Z","steps":["trace[1085189230] 'process raft request' (duration: 484.86112ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:07:27.216429Z","caller":"traceutil/trace.go:171","msg":"trace[1218976851] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"471.5512ms","start":"2026-05-19T03:07:26.744854Z","end":"2026-05-19T03:07:27.216405Z","steps":["trace[1218976851] 'process raft request' (duration: 471.054358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.216483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:26.730727Z","time spent":"485.72192ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:07:27.216489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:26.744832Z","time spent":"471.629202ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:07:27.216747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279483602s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T03:07:27.217969Z","caller":"traceutil/trace.go:171","msg":"trace[1686972183] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5383; }","duration":"1.280707681s","start":"2026-05-19T03:07:25.937246Z","end":"2026-05-19T03:07:27.217954Z","steps":["trace[1686972183] 'agreement among raft nodes before linearized reading' (duration: 1.279454432s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.218006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:25.9372Z","time spent":"1.280794563s","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-19T03:07:27.216922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064856129s","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-19T03:07:27.218544Z","caller":"traceutil/trace.go:171","msg":"trace[1431320250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5383; }","duration":"1.066515028s","start":"2026-05-19T03:07:26.152019Z","end":"2026-05-19T03:07:27.218534Z","steps":["trace[1431320250] 'agreement among raft nodes before linearized reading' (duration: 1.064828998s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.217204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.800881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T03:07:27.219159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:26.151997Z","time spent":"1.067149403s","remote":"127.0.0.1:55664","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-19T03:07:27.219175Z","caller":"traceutil/trace.go:171","msg":"trace[1106144031] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:5383; }","duration":"625.790617ms","start":"2026-05-19T03:07:26.59336Z","end":"2026-05-19T03:07:27.219151Z","steps":["trace[1106144031] 'agreement among raft nodes before linearized reading' (duration: 623.823571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.219207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:26.593341Z","time spent":"625.854869ms","remote":"127.0.0.1:52838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-05-19T03:07:27.217238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.572346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:07:27.219303Z","caller":"traceutil/trace.go:171","msg":"trace[1217654542] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5383; }","duration":"754.650905ms","start":"2026-05-19T03:07:26.464645Z","end":"2026-05-19T03:07:27.219296Z","steps":["trace[1217654542] 'agreement among raft nodes before linearized reading' (duration: 752.575676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.219322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:26.464636Z","time spent":"754.679166ms","remote":"127.0.0.1:37820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T03:07:27.217264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.627399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:07:27.219356Z","caller":"traceutil/trace.go:171","msg":"trace[875173276] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5383; }","duration":"825.742629ms","start":"2026-05-19T03:07:26.393604Z","end":"2026-05-19T03:07:27.219347Z","steps":["trace[875173276] 'agreement among raft nodes before linearized reading' (duration: 823.644649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.219432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:26.393595Z","time spent":"825.828461ms","remote":"127.0.0.1:55634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-05-19T03:07:27.21729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.290044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:07:27.219483Z","caller":"traceutil/trace.go:171","msg":"trace[981046269] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5383; }","duration":"826.523797ms","start":"2026-05-19T03:07:26.392953Z","end":"2026-05-19T03:07:27.219477Z","steps":["trace[981046269] 'agreement among raft nodes before linearized reading' (duration: 824.322155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.219503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:26.392935Z","time spent":"826.560208ms","remote":"127.0.0.1:55634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-05-19T03:07:27.217316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.821011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:07:27.219556Z","caller":"traceutil/trace.go:171","msg":"trace[1886910899] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:5383; }","duration":"853.084724ms","start":"2026-05-19T03:07:26.366449Z","end":"2026-05-19T03:07:27.219533Z","steps":["trace[1886910899] 'agreement among raft nodes before linearized reading' (duration: 850.852222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.217632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.227846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T03:07:27.219696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:26.366428Z","time spent":"853.239017ms","remote":"127.0.0.1:38066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-05-19T03:07:27.219707Z","caller":"traceutil/trace.go:171","msg":"trace[927804773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5383; }","duration":"916.339286ms","start":"2026-05-19T03:07:26.303351Z","end":"2026-05-19T03:07:27.219691Z","steps":["trace[927804773] 'agreement among raft nodes before linearized reading' (duration: 913.97401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.219752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:26.303331Z","time spent":"916.408678ms","remote":"127.0.0.1:55664","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-19T03:07:27.217698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025275329s","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":"info","ts":"2026-05-19T03:07:27.219801Z","caller":"traceutil/trace.go:171","msg":"trace[920742453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5383; }","duration":"1.027394169s","start":"2026-05-19T03:07:26.192397Z","end":"2026-05-19T03:07:27.219791Z","steps":["trace[920742453] 'agreement among raft nodes before linearized reading' (duration: 1.025255279s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.219845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:26.192386Z","time spent":"1.02744172s","remote":"127.0.0.1:55664","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-19T03:07:27.21776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036221434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T03:07:27.219945Z","caller":"traceutil/trace.go:171","msg":"trace[1215912576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5383; }","duration":"1.038422425s","start":"2026-05-19T03:07:26.181512Z","end":"2026-05-19T03:07:27.219934Z","steps":["trace[1215912576] 'agreement among raft nodes before linearized reading' (duration: 1.036200063s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:07:27.219982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:07:26.181501Z","time spent":"1.038469776s","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T03:07:49.870547Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2887} {"level":"info","ts":"2026-05-19T03:07:49.961771Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2887,"took":"89.80447ms","hash":201637729,"current-db-size-bytes":53514240,"current-db-size":"54 MB","current-db-size-in-use-bytes":29065216,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-19T03:07:49.961878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":201637729,"revision":2887,"compact-revision":-1} {"level":"info","ts":"2026-05-19T03:08:38.109114Z","caller":"traceutil/trace.go:171","msg":"trace[629534605] linearizableReadLoop","detail":"{readStateIndex:6064; appliedIndex:6063; }","duration":"230.652677ms","start":"2026-05-19T03:08:37.878434Z","end":"2026-05-19T03:08:38.109086Z","steps":["trace[629534605] 'read index received' (duration: 230.35681ms)","trace[629534605] 'applied index is now lower than readState.Index' (duration: 294.877µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:08:38.109257Z","caller":"traceutil/trace.go:171","msg":"trace[1110399854] transaction","detail":"{read_only:false; response_revision:5850; number_of_response:1; }","duration":"235.217593ms","start":"2026-05-19T03:08:37.874028Z","end":"2026-05-19T03:08:38.109246Z","steps":["trace[1110399854] 'process raft request' (duration: 234.855595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:38.109594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.125018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:08:38.10968Z","caller":"traceutil/trace.go:171","msg":"trace[428234951] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:5850; }","duration":"231.244301ms","start":"2026-05-19T03:08:37.878413Z","end":"2026-05-19T03:08:38.109657Z","steps":["trace[428234951] 'agreement among raft nodes before linearized reading' (duration: 231.078167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:38.109733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.153037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T03:08:38.10985Z","caller":"traceutil/trace.go:171","msg":"trace[88860192] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:5850; }","duration":"122.287ms","start":"2026-05-19T03:08:37.987511Z","end":"2026-05-19T03:08:38.109798Z","steps":["trace[88860192] 'agreement among raft nodes before linearized reading' (duration: 121.901061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:42.085805Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765478756530853,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:42.586487Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765478756530853,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:43.08726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765478756530853,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:43.588474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765478756530853,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:44.088679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765478756530853,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:44.377223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000799196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-19T03:08:44.377302Z","caller":"traceutil/trace.go:171","msg":"trace[200220248] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000936709s","start":"2026-05-19T03:08:42.376348Z","end":"2026-05-19T03:08:44.377285Z","steps":["trace[200220248] 'agreement among raft nodes before linearized reading' (duration: 2.000834407s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.377346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:42.376323Z","time spent":"2.001013002s","remote":"127.0.0.1:55356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/19 03:08:44 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T03:08:44.41121Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.830666033s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T03:08:44.411624Z","caller":"traceutil/trace.go:171","msg":"trace[355505704] transaction","detail":"{read_only:false; response_revision:5865; number_of_response:1; }","duration":"2.831159564s","start":"2026-05-19T03:08:41.58044Z","end":"2026-05-19T03:08:44.4116Z","steps":["trace[355505704] 'process raft request' (duration: 2.83095974s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.411748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:41.580416Z","time spent":"2.831257747s","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:08:44.414576Z","caller":"traceutil/trace.go:171","msg":"trace[528137231] transaction","detail":"{read_only:false; response_revision:5866; number_of_response:1; }","duration":"2.291957645s","start":"2026-05-19T03:08:42.122603Z","end":"2026-05-19T03:08:44.414561Z","steps":["trace[528137231] 'process raft request' (duration: 2.29172505s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.414639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:42.12258Z","time spent":"2.292027426s","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:08:44.414758Z","caller":"traceutil/trace.go:171","msg":"trace[1522380441] transaction","detail":"{read_only:false; response_revision:5867; number_of_response:1; }","duration":"915.422931ms","start":"2026-05-19T03:08:43.49931Z","end":"2026-05-19T03:08:44.414733Z","steps":["trace[1522380441] 'process raft request' (duration: 915.112415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.41489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:43.499288Z","time spent":"915.536304ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:08:44.414793Z","caller":"traceutil/trace.go:171","msg":"trace[2060655786] transaction","detail":"{read_only:false; response_revision:5868; number_of_response:1; }","duration":"912.959904ms","start":"2026-05-19T03:08:43.501819Z","end":"2026-05-19T03:08:44.414779Z","steps":["trace[2060655786] 'process raft request' (duration: 912.78659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.415109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:43.501811Z","time spent":"913.22998ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:08:44.415327Z","caller":"traceutil/trace.go:171","msg":"trace[684732420] linearizableReadLoop","detail":"{readStateIndex:6085; appliedIndex:6079; }","duration":"2.829584278s","start":"2026-05-19T03:08:41.585732Z","end":"2026-05-19T03:08:44.415316Z","steps":["trace[684732420] 'read index received' (duration: 2.825755389s)","trace[684732420] 'applied index is now lower than readState.Index' (duration: 3.828099ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:08:44.415384Z","caller":"traceutil/trace.go:171","msg":"trace[1236353466] transaction","detail":"{read_only:false; response_revision:5870; number_of_response:1; }","duration":"802.371617ms","start":"2026-05-19T03:08:43.613Z","end":"2026-05-19T03:08:44.415372Z","steps":["trace[1236353466] 'process raft request' (duration: 802.244214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.415443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:43.612983Z","time spent":"802.432808ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:08:44.415577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.829829024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-19T03:08:44.41568Z","caller":"traceutil/trace.go:171","msg":"trace[1117888135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5870; }","duration":"2.829894365s","start":"2026-05-19T03:08:41.585709Z","end":"2026-05-19T03:08:44.415603Z","steps":["trace[1117888135] 'agreement among raft nodes before linearized reading' (duration: 2.82968821s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.415986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333772305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-19T03:08:44.415994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:41.585697Z","time spent":"2.830009648s","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T03:08:44.416021Z","caller":"traceutil/trace.go:171","msg":"trace[1388275899] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:5870; }","duration":"1.333847687s","start":"2026-05-19T03:08:43.082164Z","end":"2026-05-19T03:08:44.416012Z","steps":["trace[1388275899] 'agreement among raft nodes before linearized reading' (duration: 1.33352648s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.416051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:43.082144Z","time spent":"1.333901059s","remote":"127.0.0.1:55396","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.113\" "} {"level":"info","ts":"2026-05-19T03:08:44.416224Z","caller":"traceutil/trace.go:171","msg":"trace[1685723842] transaction","detail":"{read_only:false; response_revision:5869; number_of_response:1; }","duration":"914.333277ms","start":"2026-05-19T03:08:43.501857Z","end":"2026-05-19T03:08:44.41619Z","steps":["trace[1685723842] 'process raft request' (duration: 912.77596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.416322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.604739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"warn","ts":"2026-05-19T03:08:44.416341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:43.501834Z","time spent":"914.44949ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:08:44.416355Z","caller":"traceutil/trace.go:171","msg":"trace[1409507665] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5870; }","duration":"415.680391ms","start":"2026-05-19T03:08:44.000665Z","end":"2026-05-19T03:08:44.416345Z","steps":["trace[1409507665] 'agreement among raft nodes before linearized reading' (duration: 415.600859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.416379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:44.000641Z","time spent":"415.732602ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2009,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-05-19T03:08:44.416491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09207044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T03:08:44.416516Z","caller":"traceutil/trace.go:171","msg":"trace[215184347] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5870; }","duration":"1.092098381s","start":"2026-05-19T03:08:43.324411Z","end":"2026-05-19T03:08:44.416509Z","steps":["trace[215184347] 'agreement among raft nodes before linearized reading' (duration: 1.09205915s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.416534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:43.324393Z","time spent":"1.092136281s","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-19T03:08:44.41668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245933098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-05-19T03:08:44.416705Z","caller":"traceutil/trace.go:171","msg":"trace[583230923] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5870; }","duration":"1.24599435s","start":"2026-05-19T03:08:43.170705Z","end":"2026-05-19T03:08:44.416699Z","steps":["trace[583230923] 'agreement among raft nodes before linearized reading' (duration: 1.245927138s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.416707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.142151092s","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-19T03:08:44.416975Z","caller":"traceutil/trace.go:171","msg":"trace[324871841] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:5870; }","duration":"2.14245882s","start":"2026-05-19T03:08:42.274504Z","end":"2026-05-19T03:08:44.416963Z","steps":["trace[324871841] 'agreement among raft nodes before linearized reading' (duration: 2.142117572s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.417017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:42.274485Z","time spent":"2.142522001s","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":95,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-19T03:08:44.417233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.790813801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T03:08:44.417273Z","caller":"traceutil/trace.go:171","msg":"trace[1369778044] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5870; }","duration":"1.790888863s","start":"2026-05-19T03:08:42.626375Z","end":"2026-05-19T03:08:44.417264Z","steps":["trace[1369778044] 'agreement among raft nodes before linearized reading' (duration: 1.790805921s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.417285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.369069893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T03:08:44.417288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.827791566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-19T03:08:44.417304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:42.626359Z","time spent":"1.790936184s","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":38,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-05-19T03:08:44.417322Z","caller":"traceutil/trace.go:171","msg":"trace[1097961314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5870; }","duration":"2.827845778s","start":"2026-05-19T03:08:41.589467Z","end":"2026-05-19T03:08:44.417312Z","steps":["trace[1097961314] 'agreement among raft nodes before linearized reading' (duration: 2.827766946s)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:08:44.417333Z","caller":"traceutil/trace.go:171","msg":"trace[1584713208] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:5870; }","duration":"2.369135414s","start":"2026-05-19T03:08:42.048171Z","end":"2026-05-19T03:08:44.417307Z","steps":["trace[1584713208] 'agreement among raft nodes before linearized reading' (duration: 2.369086803s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.417341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:41.589454Z","time spent":"2.827882919s","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T03:08:44.417367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:42.048148Z","time spent":"2.369209336s","remote":"127.0.0.1:38066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-05-19T03:08:44.417473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.971049183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T03:08:44.416722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:43.170684Z","time spent":"1.246034781s","remote":"127.0.0.1:55562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":698,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"info","ts":"2026-05-19T03:08:44.417511Z","caller":"traceutil/trace.go:171","msg":"trace[1527212294] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:5870; }","duration":"1.971090694s","start":"2026-05-19T03:08:42.446411Z","end":"2026-05-19T03:08:44.417501Z","steps":["trace[1527212294] 'agreement among raft nodes before linearized reading' (duration: 1.971024033s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.417542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:42.446367Z","time spent":"1.971166386s","remote":"127.0.0.1:41808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":21,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"warn","ts":"2026-05-19T03:08:44.417235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.827220193s","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-19T03:08:44.417697Z","caller":"traceutil/trace.go:171","msg":"trace[675590673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5870; }","duration":"2.827702854s","start":"2026-05-19T03:08:41.589987Z","end":"2026-05-19T03:08:44.41769Z","steps":["trace[675590673] 'agreement among raft nodes before linearized reading' (duration: 2.827169111s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.417724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:41.58998Z","time spent":"2.827734975s","remote":"127.0.0.1:55664","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-19T03:08:44.417884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.828401701s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-19T03:08:44.417925Z","caller":"traceutil/trace.go:171","msg":"trace[1303009245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5870; }","duration":"2.828470282s","start":"2026-05-19T03:08:41.589443Z","end":"2026-05-19T03:08:44.417913Z","steps":["trace[1303009245] 'agreement among raft nodes before linearized reading' (duration: 2.825989834s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:44.417952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:41.58943Z","time spent":"2.828515394s","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T03:10:17.05913Z","caller":"traceutil/trace.go:171","msg":"trace[1556972109] transaction","detail":"{read_only:false; response_revision:6543; number_of_response:1; }","duration":"101.057509ms","start":"2026-05-19T03:10:16.958047Z","end":"2026-05-19T03:10:17.059104Z","steps":["trace[1556972109] 'process raft request' (duration: 55.413803ms)","trace[1556972109] 'compare' (duration: 45.482142ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:10:17.059348Z","caller":"traceutil/trace.go:171","msg":"trace[575286703] transaction","detail":"{read_only:false; response_revision:6544; number_of_response:1; }","duration":"100.108408ms","start":"2026-05-19T03:10:16.959211Z","end":"2026-05-19T03:10:17.059319Z","steps":["trace[575286703] 'process raft request' (duration: 99.853852ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:10:17.059668Z","caller":"traceutil/trace.go:171","msg":"trace[209380669] transaction","detail":"{read_only:false; response_revision:6545; number_of_response:1; }","duration":"100.264831ms","start":"2026-05-19T03:10:16.959391Z","end":"2026-05-19T03:10:17.059656Z","steps":["trace[209380669] 'process raft request' (duration: 99.851291ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:10:17.059929Z","caller":"traceutil/trace.go:171","msg":"trace[1593430025] transaction","detail":"{read_only:false; response_revision:6547; number_of_response:1; }","duration":"100.160219ms","start":"2026-05-19T03:10:16.959749Z","end":"2026-05-19T03:10:17.059909Z","steps":["trace[1593430025] 'process raft request' (duration: 100.074037ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:10:17.060071Z","caller":"traceutil/trace.go:171","msg":"trace[483232647] transaction","detail":"{read_only:false; response_revision:6546; number_of_response:1; }","duration":"100.373094ms","start":"2026-05-19T03:10:16.959676Z","end":"2026-05-19T03:10:17.060049Z","steps":["trace[483232647] 'process raft request' (duration: 99.933624ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:12:49.876522Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5555} {"level":"info","ts":"2026-05-19T03:12:49.957214Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5555,"took":"79.284981ms","hash":2631859367,"current-db-size-bytes":53514240,"current-db-size":"54 MB","current-db-size-in-use-bytes":18104320,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-19T03:12:49.957275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2631859367,"revision":5555,"compact-revision":2887} {"level":"info","ts":"2026-05-19T03:13:14.325227Z","caller":"traceutil/trace.go:171","msg":"trace[1708310097] linearizableReadLoop","detail":"{readStateIndex:7800; appliedIndex:7798; }","duration":"332.735636ms","start":"2026-05-19T03:13:13.992458Z","end":"2026-05-19T03:13:14.325194Z","steps":["trace[1708310097] 'read index received' (duration: 330.573552ms)","trace[1708310097] 'applied index is now lower than readState.Index' (duration: 2.160774ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:13:14.325372Z","caller":"traceutil/trace.go:171","msg":"trace[1946317816] transaction","detail":"{read_only:false; response_revision:7518; number_of_response:1; }","duration":"334.721527ms","start":"2026-05-19T03:13:13.990639Z","end":"2026-05-19T03:13:14.32536Z","steps":["trace[1946317816] 'process raft request' (duration: 334.48162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:13:14.326182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:13:13.990621Z","time spent":"335.455705ms","remote":"127.0.0.1:55664","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-19T03:13:14.325356Z","caller":"traceutil/trace.go:171","msg":"trace[2133862535] transaction","detail":"{read_only:false; response_revision:7517; number_of_response:1; }","duration":"336.228394ms","start":"2026-05-19T03:13:13.989108Z","end":"2026-05-19T03:13:14.325336Z","steps":["trace[2133862535] 'process raft request' (duration: 333.981308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:13:14.325723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.678189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/\" range_end:\"/registry/deployments/local-path-storage0\" limit:500 ","response":"range_response_count:1 size:3760"} {"level":"warn","ts":"2026-05-19T03:13:14.326452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:13:13.989097Z","time spent":"337.22958ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:13:14.325809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.346611ms","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-19T03:13:14.326519Z","caller":"traceutil/trace.go:171","msg":"trace[1813212742] range","detail":"{range_begin:/registry/deployments/local-path-storage/; range_end:/registry/deployments/local-path-storage0; response_count:1; response_revision:7518; }","duration":"285.458388ms","start":"2026-05-19T03:13:14.040993Z","end":"2026-05-19T03:13:14.326452Z","steps":["trace[1813212742] 'agreement among raft nodes before linearized reading' (duration: 284.560956ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:13:14.326593Z","caller":"traceutil/trace.go:171","msg":"trace[22952638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7518; }","duration":"334.086799ms","start":"2026-05-19T03:13:13.992447Z","end":"2026-05-19T03:13:14.326534Z","steps":["trace[22952638] 'agreement among raft nodes before linearized reading' (duration: 333.3047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:13:14.325885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.28305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T03:13:14.326949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:13:13.992441Z","time spent":"334.491011ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T03:13:14.327005Z","caller":"traceutil/trace.go:171","msg":"trace[586608798] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:7518; }","duration":"150.412208ms","start":"2026-05-19T03:13:14.176543Z","end":"2026-05-19T03:13:14.326955Z","steps":["trace[586608798] 'agreement among raft nodes before linearized reading' (duration: 149.28029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:13:14.325958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.488759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"info","ts":"2026-05-19T03:13:14.327181Z","caller":"traceutil/trace.go:171","msg":"trace[1164634963] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:7518; }","duration":"295.704179ms","start":"2026-05-19T03:13:14.031447Z","end":"2026-05-19T03:13:14.327151Z","steps":["trace[1164634963] 'agreement among raft nodes before linearized reading' (duration: 294.464967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:13:14.326026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.147576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-19T03:13:14.327452Z","caller":"traceutil/trace.go:171","msg":"trace[1193894558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7518; }","duration":"334.574323ms","start":"2026-05-19T03:13:13.992867Z","end":"2026-05-19T03:13:14.327441Z","steps":["trace[1193894558] 'agreement among raft nodes before linearized reading' (duration: 333.115356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:13:14.327536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:13:13.992864Z","time spent":"334.637784ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T03:13:14.326078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.895199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:4637"} {"level":"info","ts":"2026-05-19T03:13:14.327648Z","caller":"traceutil/trace.go:171","msg":"trace[1521593349] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:7518; }","duration":"334.848359ms","start":"2026-05-19T03:13:13.992778Z","end":"2026-05-19T03:13:14.327626Z","steps":["trace[1521593349] 'agreement among raft nodes before linearized reading' (duration: 332.777857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:13:14.327699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:13:13.992774Z","time spent":"334.912282ms","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":4660,"request content":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" "}