{"level":"warn","ts":"2026-07-17T11:56:52.843185Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T11:56:52.84326Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.60: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.19.213.60:2380","--initial-cluster=instance=https://199.19.213.60:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.60:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.60: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-07-17T11:56:52.843332Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T11:56:52.843344Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.60:2380"]} {"level":"info","ts":"2026-07-17T11:56:52.843362Z","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-07-17T11:56:52.843995Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.60:2379"]} {"level":"info","ts":"2026-07-17T11:56:52.844074Z","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.19.213.60:2380"],"listen-peer-urls":["https://199.19.213.60:2380"],"advertise-client-urls":["https://199.19.213.60:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.60:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.60: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-07-17T11:56:52.849023Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.752392ms"} {"level":"info","ts":"2026-07-17T11:56:52.857413Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3490cdb1ff3fc828","cluster-id":"872e6d60ada2e30c"} {"level":"info","ts":"2026-07-17T11:56:52.857539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T11:56:52.85758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became follower at term 0"} {"level":"info","ts":"2026-07-17T11:56:52.857611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3490cdb1ff3fc828 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T11:56:52.857626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became follower at term 1"} {"level":"info","ts":"2026-07-17T11:56:52.857685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 switched to configuration voters=(3787753450993862696)"} {"level":"warn","ts":"2026-07-17T11:56:52.863984Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T11:56:52.865463Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T11:56:52.868948Z","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-07-17T11:56:52.874826Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3490cdb1ff3fc828","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T11:56:52.875219Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3490cdb1ff3fc828","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T11:56:52.87538Z","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-07-17T11:56:52.87547Z","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-07-17T11:56:52.875487Z","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-07-17T11:56:52.877251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 switched to configuration voters=(3787753450993862696)"} {"level":"info","ts":"2026-07-17T11:56:52.877644Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"872e6d60ada2e30c","local-member-id":"3490cdb1ff3fc828","added-peer-id":"3490cdb1ff3fc828","added-peer-peer-urls":["https://199.19.213.60:2380"]} {"level":"info","ts":"2026-07-17T11:56:52.881592Z","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-07-17T11:56:52.881767Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.60:2380"} {"level":"info","ts":"2026-07-17T11:56:52.881801Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.60:2380"} {"level":"info","ts":"2026-07-17T11:56:52.883045Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3490cdb1ff3fc828","initial-advertise-peer-urls":["https://199.19.213.60:2380"],"listen-peer-urls":["https://199.19.213.60:2380"],"advertise-client-urls":["https://199.19.213.60:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.60:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T11:56:52.883097Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T11:56:52.95845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T11:56:52.958504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T11:56:52.958535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 received MsgPreVoteResp from 3490cdb1ff3fc828 at term 1"} {"level":"info","ts":"2026-07-17T11:56:52.958559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became candidate at term 2"} {"level":"info","ts":"2026-07-17T11:56:52.958576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 received MsgVoteResp from 3490cdb1ff3fc828 at term 2"} {"level":"info","ts":"2026-07-17T11:56:52.958695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3490cdb1ff3fc828 became leader at term 2"} {"level":"info","ts":"2026-07-17T11:56:52.958715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3490cdb1ff3fc828 elected leader 3490cdb1ff3fc828 at term 2"} {"level":"info","ts":"2026-07-17T11:56:52.959854Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3490cdb1ff3fc828","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.60:2379]}","request-path":"/0/members/3490cdb1ff3fc828/attributes","cluster-id":"872e6d60ada2e30c","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T11:56:52.96004Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T11:56:52.960564Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:56:52.960896Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T11:56:52.961469Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"872e6d60ada2e30c","local-member-id":"3490cdb1ff3fc828","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:56:52.961604Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:56:52.961639Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:56:52.962483Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T11:56:52.962537Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T11:56:52.96516Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.60:2379"} {"level":"info","ts":"2026-07-17T11:56:52.965671Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T11:56:58.033125Z","caller":"traceutil/trace.go:171","msg":"trace[1883608074] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"490.423083ms","start":"2026-07-17T11:56:57.542683Z","end":"2026-07-17T11:56:58.033106Z","steps":["trace[1883608074] 'process raft request' (duration: 490.309943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:56:58.033528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:56:57.542647Z","time spent":"490.549405ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:56:58.035523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:56:57.721707Z","time spent":"313.813064ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-17T11:56:58.035613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.161827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-07-17T11:56:58.03555Z","caller":"traceutil/trace.go:171","msg":"trace[1583890870] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:217; }","duration":"278.053936ms","start":"2026-07-17T11:56:57.757448Z","end":"2026-07-17T11:56:58.035502Z","steps":["trace[1583890870] 'read index received' (duration: 275.809341ms)","trace[1583890870] 'applied index is now lower than readState.Index' (duration: 2.242615ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:56:58.035668Z","caller":"traceutil/trace.go:171","msg":"trace[2087461915] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:212; }","duration":"278.259057ms","start":"2026-07-17T11:56:57.757396Z","end":"2026-07-17T11:56:58.035655Z","steps":["trace[2087461915] 'agreement among raft nodes before linearized reading' (duration: 278.162157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:56:59.41017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422953109766994851,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T11:56:59.719729Z","caller":"traceutil/trace.go:171","msg":"trace[2134726969] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"810.397429ms","start":"2026-07-17T11:56:58.90931Z","end":"2026-07-17T11:56:59.719708Z","steps":["trace[2134726969] 'read index received' (duration: 810.239294ms)","trace[2134726969] 'applied index is now lower than readState.Index' (duration: 157.165µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:56:59.719831Z","caller":"traceutil/trace.go:171","msg":"trace[319904929] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"830.038646ms","start":"2026-07-17T11:56:58.889785Z","end":"2026-07-17T11:56:59.719824Z","steps":["trace[319904929] 'process raft request' (duration: 829.789758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:56:59.720049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:56:58.889761Z","time spent":"830.089617ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:56:59.720085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.903053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:56:59.720169Z","caller":"traceutil/trace.go:171","msg":"trace[1943560569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:302; }","duration":"683.044338ms","start":"2026-07-17T11:56:59.037105Z","end":"2026-07-17T11:56:59.720149Z","steps":["trace[1943560569] 'agreement among raft nodes before linearized reading' (duration: 682.906483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:56:59.720205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:56:59.037086Z","time spent":"683.11152ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T11:56:59.720318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.012441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:56:59.720347Z","caller":"traceutil/trace.go:171","msg":"trace[519911167] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:302; }","duration":"811.084232ms","start":"2026-07-17T11:56:58.909254Z","end":"2026-07-17T11:56:59.720338Z","steps":["trace[519911167] 'agreement among raft nodes before linearized reading' (duration: 811.035021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:56:59.720368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:56:58.909229Z","time spent":"811.133743ms","remote":"127.0.0.1:37124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" "} {"level":"warn","ts":"2026-07-17T11:57:28.72387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.640875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T11:57:28.724246Z","caller":"traceutil/trace.go:171","msg":"trace[934901339] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"215.73693ms","start":"2026-07-17T11:57:28.508489Z","end":"2026-07-17T11:57:28.724226Z","steps":["trace[934901339] 'process raft request' (duration: 215.568015ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:28.724233Z","caller":"traceutil/trace.go:171","msg":"trace[336241520] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"215.949346ms","start":"2026-07-17T11:57:28.508239Z","end":"2026-07-17T11:57:28.724189Z","steps":["trace[336241520] 'process raft request' (duration: 104.772217ms)","trace[336241520] 'compare' (duration: 110.473361ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:57:28.724385Z","caller":"traceutil/trace.go:171","msg":"trace[701189216] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"200.822681ms","start":"2026-07-17T11:57:28.523518Z","end":"2026-07-17T11:57:28.724341Z","steps":["trace[701189216] 'process raft request' (duration: 200.668877ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:28.724496Z","caller":"traceutil/trace.go:171","msg":"trace[874787101] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"181.990882ms","start":"2026-07-17T11:57:28.542497Z","end":"2026-07-17T11:57:28.724488Z","steps":["trace[874787101] 'process raft request' (duration: 181.855298ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:28.724469Z","caller":"traceutil/trace.go:171","msg":"trace[71880478] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"189.40167ms","start":"2026-07-17T11:57:28.535011Z","end":"2026-07-17T11:57:28.724412Z","steps":["trace[71880478] 'process raft request' (duration: 189.265737ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:28.724551Z","caller":"traceutil/trace.go:171","msg":"trace[1605383742] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"213.395231ms","start":"2026-07-17T11:57:28.511152Z","end":"2026-07-17T11:57:28.724547Z","steps":["trace[1605383742] 'process raft request' (duration: 212.98425ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:28.724575Z","caller":"traceutil/trace.go:171","msg":"trace[1013203167] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"181.995012ms","start":"2026-07-17T11:57:28.542569Z","end":"2026-07-17T11:57:28.724564Z","steps":["trace[1013203167] 'process raft request' (duration: 181.807877ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:28.724626Z","caller":"traceutil/trace.go:171","msg":"trace[986251610] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"172.416948ms","start":"2026-07-17T11:57:28.552202Z","end":"2026-07-17T11:57:28.724619Z","steps":["trace[986251610] 'process raft request' (duration: 172.208513ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:28.724652Z","caller":"traceutil/trace.go:171","msg":"trace[1057739117] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"147.791842ms","start":"2026-07-17T11:57:28.576845Z","end":"2026-07-17T11:57:28.724637Z","steps":["trace[1057739117] 'process raft request' (duration: 147.74516ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:28.724654Z","caller":"traceutil/trace.go:171","msg":"trace[790242342] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"168.91403ms","start":"2026-07-17T11:57:28.555725Z","end":"2026-07-17T11:57:28.724639Z","steps":["trace[790242342] 'process raft request' (duration: 168.795267ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:28.72481Z","caller":"traceutil/trace.go:171","msg":"trace[669439840] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"190.251101ms","start":"2026-07-17T11:57:28.53454Z","end":"2026-07-17T11:57:28.724791Z","steps":["trace[669439840] 'process raft request' (duration: 189.692777ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:34.709921Z","caller":"traceutil/trace.go:171","msg":"trace[1282095387] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"210.67832ms","start":"2026-07-17T11:57:34.499221Z","end":"2026-07-17T11:57:34.709899Z","steps":["trace[1282095387] 'process raft request' (duration: 210.217649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:35.068116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.683205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T11:57:35.068301Z","caller":"traceutil/trace.go:171","msg":"trace[607054686] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"255.92446ms","start":"2026-07-17T11:57:34.81236Z","end":"2026-07-17T11:57:35.068284Z","steps":["trace[607054686] 'read index received' (duration: 4.917308ms)","trace[607054686] 'applied index is now lower than readState.Index' (duration: 251.005762ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:57:35.068356Z","caller":"traceutil/trace.go:171","msg":"trace[964777962] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"300.480659ms","start":"2026-07-17T11:57:34.767846Z","end":"2026-07-17T11:57:35.068327Z","steps":["trace[964777962] 'process raft request' (duration: 49.504877ms)","trace[964777962] 'compare' (duration: 250.523251ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:57:35.068608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:57:34.767822Z","time spent":"300.751805ms","remote":"127.0.0.1:37438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:57:35.068784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.395372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sqxzj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-17T11:57:35.068882Z","caller":"traceutil/trace.go:171","msg":"trace[1178376705] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sqxzj; range_end:; response_count:1; response_revision:629; }","duration":"256.530724ms","start":"2026-07-17T11:57:34.812326Z","end":"2026-07-17T11:57:35.068857Z","steps":["trace[1178376705] 'agreement among raft nodes before linearized reading' (duration: 256.083284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:35.641374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.876743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T11:57:35.641518Z","caller":"traceutil/trace.go:171","msg":"trace[1373511754] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"274.466877ms","start":"2026-07-17T11:57:35.367037Z","end":"2026-07-17T11:57:35.641504Z","steps":["trace[1373511754] 'process raft request' (duration: 274.419126ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:35.641664Z","caller":"traceutil/trace.go:171","msg":"trace[380270105] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"558.755488ms","start":"2026-07-17T11:57:35.082863Z","end":"2026-07-17T11:57:35.641619Z","steps":["trace[380270105] 'read index received' (duration: 204.545327ms)","trace[380270105] 'applied index is now lower than readState.Index' (duration: 354.207251ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:57:35.641801Z","caller":"traceutil/trace.go:171","msg":"trace[1807231933] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"559.085835ms","start":"2026-07-17T11:57:35.082684Z","end":"2026-07-17T11:57:35.641769Z","steps":["trace[1807231933] 'process raft request' (duration: 204.748152ms)","trace[1807231933] 'compare' (duration: 353.75675ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:57:35.641849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.928911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:57:35.641897Z","caller":"traceutil/trace.go:171","msg":"trace[775929977] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:631; }","duration":"559.067695ms","start":"2026-07-17T11:57:35.082812Z","end":"2026-07-17T11:57:35.64188Z","steps":["trace[775929977] 'agreement among raft nodes before linearized reading' (duration: 558.934202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:35.641931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:57:35.08279Z","time spent":"559.131186ms","remote":"127.0.0.1:37164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-17T11:57:35.641945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:57:35.082654Z","time spent":"559.218659ms","remote":"127.0.0.1:37092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:57:43.617467Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422953109766995760,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:57:44.113454Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.229828053s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T11:57:44.113883Z","caller":"traceutil/trace.go:171","msg":"trace[415976975] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"1.230307552s","start":"2026-07-17T11:57:42.883555Z","end":"2026-07-17T11:57:44.113863Z","steps":["trace[415976975] 'process raft request' (duration: 1.230139339s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:44.114003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:57:42.883544Z","time spent":"1.230386374s","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:57:44.11662Z","caller":"traceutil/trace.go:171","msg":"trace[1246986855] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:757; }","duration":"999.697562ms","start":"2026-07-17T11:57:43.116904Z","end":"2026-07-17T11:57:44.116602Z","steps":["trace[1246986855] 'read index received' (duration: 996.85524ms)","trace[1246986855] 'applied index is now lower than readState.Index' (duration: 2.841382ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:57:44.116648Z","caller":"traceutil/trace.go:171","msg":"trace[101693403] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"765.561458ms","start":"2026-07-17T11:57:43.351073Z","end":"2026-07-17T11:57:44.116634Z","steps":["trace[101693403] 'process raft request' (duration: 765.428645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:44.116719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:57:43.351049Z","time spent":"765.631969ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:57:44.116743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.815975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:57:44.11677Z","caller":"traceutil/trace.go:171","msg":"trace[338986043] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:740; }","duration":"999.880536ms","start":"2026-07-17T11:57:43.116881Z","end":"2026-07-17T11:57:44.116761Z","steps":["trace[338986043] 'agreement among raft nodes before linearized reading' (duration: 999.794654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:44.116793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:57:43.116869Z","time spent":"999.918757ms","remote":"127.0.0.1:36656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-07-17T11:57:44.116951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.999848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:57:44.116982Z","caller":"traceutil/trace.go:171","msg":"trace[109613867] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:740; }","duration":"1.000037749s","start":"2026-07-17T11:57:43.116933Z","end":"2026-07-17T11:57:44.11697Z","steps":["trace[109613867] 'agreement among raft nodes before linearized reading' (duration: 999.985168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:44.117001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:57:43.11693Z","time spent":"1.00006728s","remote":"127.0.0.1:36656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-07-17T11:57:44.117055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.484366ms","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-07-17T11:57:44.117076Z","caller":"traceutil/trace.go:171","msg":"trace[1729543334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:740; }","duration":"193.542907ms","start":"2026-07-17T11:57:43.923527Z","end":"2026-07-17T11:57:44.11707Z","steps":["trace[1729543334] 'agreement among raft nodes before linearized reading' (duration: 193.456245ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:44.812787Z","caller":"traceutil/trace.go:171","msg":"trace[1379583038] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"246.265591ms","start":"2026-07-17T11:57:44.566482Z","end":"2026-07-17T11:57:44.812747Z","steps":["trace[1379583038] 'read index received' (duration: 246.091427ms)","trace[1379583038] 'applied index is now lower than readState.Index' (duration: 172.874µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:57:44.81299Z","caller":"traceutil/trace.go:171","msg":"trace[1285214432] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"248.160653ms","start":"2026-07-17T11:57:44.56482Z","end":"2026-07-17T11:57:44.812981Z","steps":["trace[1285214432] 'process raft request' (duration: 247.805985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:44.813255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.766472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2074"} {"level":"info","ts":"2026-07-17T11:57:44.81329Z","caller":"traceutil/trace.go:171","msg":"trace[6430921] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:747; }","duration":"246.861044ms","start":"2026-07-17T11:57:44.566422Z","end":"2026-07-17T11:57:44.813283Z","steps":["trace[6430921] 'agreement among raft nodes before linearized reading' (duration: 246.751792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:44.813725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.05063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2074"} {"level":"info","ts":"2026-07-17T11:57:44.813981Z","caller":"traceutil/trace.go:171","msg":"trace[634133999] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:747; }","duration":"148.363196ms","start":"2026-07-17T11:57:44.665585Z","end":"2026-07-17T11:57:44.813948Z","steps":["trace[634133999] 'agreement among raft nodes before linearized reading' (duration: 147.713522ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:47.930634Z","caller":"traceutil/trace.go:171","msg":"trace[1931340696] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"131.895559ms","start":"2026-07-17T11:57:47.798712Z","end":"2026-07-17T11:57:47.930608Z","steps":["trace[1931340696] 'read index received' (duration: 131.651823ms)","trace[1931340696] 'applied index is now lower than readState.Index' (duration: 242.846µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:57:47.930716Z","caller":"traceutil/trace.go:171","msg":"trace[577873052] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"573.471872ms","start":"2026-07-17T11:57:47.357223Z","end":"2026-07-17T11:57:47.930695Z","steps":["trace[577873052] 'process raft request' (duration: 573.236967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:47.930778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.050213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:57:47.930835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:57:47.357201Z","time spent":"573.566444ms","remote":"127.0.0.1:37092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:57:47.930907Z","caller":"traceutil/trace.go:171","msg":"trace[268624485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"132.167076ms","start":"2026-07-17T11:57:47.798699Z","end":"2026-07-17T11:57:47.930866Z","steps":["trace[268624485] 'agreement among raft nodes before linearized reading' (duration: 132.017723ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:57:48.26057Z","caller":"traceutil/trace.go:171","msg":"trace[858932058] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"124.980738ms","start":"2026-07-17T11:57:48.135566Z","end":"2026-07-17T11:57:48.260547Z","steps":["trace[858932058] 'read index received' (duration: 124.720803ms)","trace[858932058] 'applied index is now lower than readState.Index' (duration: 258.945µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:57:48.260606Z","caller":"traceutil/trace.go:171","msg":"trace[1004688134] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"127.074273ms","start":"2026-07-17T11:57:48.133509Z","end":"2026-07-17T11:57:48.260584Z","steps":["trace[1004688134] 'process raft request' (duration: 126.883359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:48.260841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.206003ms","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-07-17T11:57:48.260956Z","caller":"traceutil/trace.go:171","msg":"trace[991626919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"125.415039ms","start":"2026-07-17T11:57:48.135521Z","end":"2026-07-17T11:57:48.260936Z","steps":["trace[991626919] 'agreement among raft nodes before linearized reading' (duration: 125.123301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:53.056246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.133158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T11:57:53.05632Z","caller":"traceutil/trace.go:171","msg":"trace[1105465514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"181.24055ms","start":"2026-07-17T11:57:52.875062Z","end":"2026-07-17T11:57:53.056302Z","steps":["trace[1105465514] 'range keys from in-memory index tree' (duration: 180.957335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:53.056333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.332689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fhgp9\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-17T11:57:53.056393Z","caller":"traceutil/trace.go:171","msg":"trace[204514698] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fhgp9; range_end:; response_count:1; response_revision:797; }","duration":"134.43371ms","start":"2026-07-17T11:57:52.921943Z","end":"2026-07-17T11:57:53.056377Z","steps":["trace[204514698] 'range keys from in-memory index tree' (duration: 134.190446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:57:53.057173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.159372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:57:53.057433Z","caller":"traceutil/trace.go:171","msg":"trace[10576200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"256.340357ms","start":"2026-07-17T11:57:52.801065Z","end":"2026-07-17T11:57:53.057405Z","steps":["trace[10576200] 'range keys from in-memory index tree' (duration: 255.01107ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:59:34.043152Z","caller":"traceutil/trace.go:171","msg":"trace[1844448833] linearizableReadLoop","detail":"{readStateIndex:2159; appliedIndex:2158; }","duration":"205.448011ms","start":"2026-07-17T11:59:33.837679Z","end":"2026-07-17T11:59:34.043127Z","steps":["trace[1844448833] 'read index received' (duration: 205.218569ms)","trace[1844448833] 'applied index is now lower than readState.Index' (duration: 228.322µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:59:34.043252Z","caller":"traceutil/trace.go:171","msg":"trace[661094625] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"234.275589ms","start":"2026-07-17T11:59:33.808949Z","end":"2026-07-17T11:59:34.043224Z","steps":["trace[661094625] 'process raft request' (duration: 234.021056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:59:34.043348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.660573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-07-17T11:59:34.043389Z","caller":"traceutil/trace.go:171","msg":"trace[258365073] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2081; }","duration":"205.759634ms","start":"2026-07-17T11:59:33.837618Z","end":"2026-07-17T11:59:34.043378Z","steps":["trace[258365073] 'agreement among raft nodes before linearized reading' (duration: 205.606342ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:59:51.887034Z","caller":"traceutil/trace.go:171","msg":"trace[183842796] linearizableReadLoop","detail":"{readStateIndex:2345; appliedIndex:2344; }","duration":"103.719794ms","start":"2026-07-17T11:59:51.783291Z","end":"2026-07-17T11:59:51.88701Z","steps":["trace[183842796] 'read index received' (duration: 32.431442ms)","trace[183842796] 'applied index is now lower than readState.Index' (duration: 71.287502ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:59:51.887084Z","caller":"traceutil/trace.go:171","msg":"trace[1442031409] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"161.117765ms","start":"2026-07-17T11:59:51.725937Z","end":"2026-07-17T11:59:51.887055Z","steps":["trace[1442031409] 'process raft request' (duration: 89.835464ms)","trace[1442031409] 'compare' (duration: 71.15375ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:59:51.887188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.878026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T11:59:51.887215Z","caller":"traceutil/trace.go:171","msg":"trace[1519612262] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2263; }","duration":"103.946606ms","start":"2026-07-17T11:59:51.783262Z","end":"2026-07-17T11:59:51.887209Z","steps":["trace[1519612262] 'agreement among raft nodes before linearized reading' (duration: 103.867155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:59:52.357713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.3677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T11:59:52.357875Z","caller":"traceutil/trace.go:171","msg":"trace[1640814349] linearizableReadLoop","detail":"{readStateIndex:2347; appliedIndex:2345; }","duration":"199.449765ms","start":"2026-07-17T11:59:52.158409Z","end":"2026-07-17T11:59:52.357859Z","steps":["trace[1640814349] 'read index received' (duration: 54.846823ms)","trace[1640814349] 'applied index is now lower than readState.Index' (duration: 144.602012ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:59:52.358197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.776769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:59:52.358258Z","caller":"traceutil/trace.go:171","msg":"trace[983516185] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2265; }","duration":"199.884061ms","start":"2026-07-17T11:59:52.158356Z","end":"2026-07-17T11:59:52.35824Z","steps":["trace[983516185] 'agreement among raft nodes before linearized reading' (duration: 199.78189ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:59:52.358228Z","caller":"traceutil/trace.go:171","msg":"trace[2100828451] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"376.779383ms","start":"2026-07-17T11:59:51.981406Z","end":"2026-07-17T11:59:52.358185Z","steps":["trace[2100828451] 'process raft request' (duration: 231.836186ms)","trace[2100828451] 'compare' (duration: 144.260419ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:59:52.35829Z","caller":"traceutil/trace.go:171","msg":"trace[1584610369] transaction","detail":"{read_only:false; response_revision:2265; number_of_response:1; }","duration":"261.717009ms","start":"2026-07-17T11:59:52.096544Z","end":"2026-07-17T11:59:52.358261Z","steps":["trace[1584610369] 'process raft request' (duration: 261.268393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:59:52.358414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.300873ms","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-07-17T11:59:52.358458Z","caller":"traceutil/trace.go:171","msg":"trace[1861109943] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2265; }","duration":"197.371914ms","start":"2026-07-17T11:59:52.161074Z","end":"2026-07-17T11:59:52.358446Z","steps":["trace[1861109943] 'agreement among raft nodes before linearized reading' (duration: 197.222402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:59:52.358468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:59:51.98138Z","time spent":"376.935385ms","remote":"127.0.0.1:37192","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-07-17T12:00:04.882858Z","caller":"traceutil/trace.go:171","msg":"trace[298673077] transaction","detail":"{read_only:false; response_revision:2379; number_of_response:1; }","duration":"134.076431ms","start":"2026-07-17T12:00:04.748729Z","end":"2026-07-17T12:00:04.882805Z","steps":["trace[298673077] 'process raft request' (duration: 133.16277ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:00:20.218471Z","caller":"traceutil/trace.go:171","msg":"trace[2146687630] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"203.641157ms","start":"2026-07-17T12:00:20.014805Z","end":"2026-07-17T12:00:20.218446Z","steps":["trace[2146687630] 'process raft request' (duration: 203.410995ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:00:20.529371Z","caller":"traceutil/trace.go:171","msg":"trace[832310471] linearizableReadLoop","detail":"{readStateIndex:2585; appliedIndex:2584; }","duration":"118.60771ms","start":"2026-07-17T12:00:20.410742Z","end":"2026-07-17T12:00:20.52935Z","steps":["trace[832310471] 'read index received' (duration: 118.350667ms)","trace[832310471] 'applied index is now lower than readState.Index' (duration: 256.173µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:00:20.529537Z","caller":"traceutil/trace.go:171","msg":"trace[635690443] transaction","detail":"{read_only:false; response_revision:2496; number_of_response:1; }","duration":"308.74042ms","start":"2026-07-17T12:00:20.220764Z","end":"2026-07-17T12:00:20.529504Z","steps":["trace[635690443] 'process raft request' (duration: 308.389847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:00:20.5297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:00:20.220736Z","time spent":"308.878242ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T12:00:20.529826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.056944ms","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-07-17T12:00:20.529892Z","caller":"traceutil/trace.go:171","msg":"trace[780447936] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2496; }","duration":"119.179435ms","start":"2026-07-17T12:00:20.4107Z","end":"2026-07-17T12:00:20.52988Z","steps":["trace[780447936] 'agreement among raft nodes before linearized reading' (duration: 118.733311ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:00:26.83665Z","caller":"traceutil/trace.go:171","msg":"trace[1854142189] transaction","detail":"{read_only:false; response_revision:2534; number_of_response:1; }","duration":"231.095945ms","start":"2026-07-17T12:00:26.605534Z","end":"2026-07-17T12:00:26.83663Z","steps":["trace[1854142189] 'process raft request' (duration: 230.961924ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:00:26.844437Z","caller":"traceutil/trace.go:171","msg":"trace[494036896] linearizableReadLoop","detail":"{readStateIndex:2626; appliedIndex:2624; }","duration":"160.077488ms","start":"2026-07-17T12:00:26.684334Z","end":"2026-07-17T12:00:26.844412Z","steps":["trace[494036896] 'read index received' (duration: 152.165038ms)","trace[494036896] 'applied index is now lower than readState.Index' (duration: 7.91147ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:00:26.844467Z","caller":"traceutil/trace.go:171","msg":"trace[780574424] transaction","detail":"{read_only:false; response_revision:2535; number_of_response:1; }","duration":"183.16668ms","start":"2026-07-17T12:00:26.661275Z","end":"2026-07-17T12:00:26.844442Z","steps":["trace[780574424] 'process raft request' (duration: 182.945358ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:00:26.844554Z","caller":"traceutil/trace.go:171","msg":"trace[254529803] transaction","detail":"{read_only:false; response_revision:2536; number_of_response:1; }","duration":"142.044135ms","start":"2026-07-17T12:00:26.7025Z","end":"2026-07-17T12:00:26.844544Z","steps":["trace[254529803] 'process raft request' (duration: 141.836173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:00:26.844829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.61866ms","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":"info","ts":"2026-07-17T12:00:26.844975Z","caller":"traceutil/trace.go:171","msg":"trace[928551491] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2537; }","duration":"131.802652ms","start":"2026-07-17T12:00:26.713152Z","end":"2026-07-17T12:00:26.844954Z","steps":["trace[928551491] 'agreement among raft nodes before linearized reading' (duration: 131.600331ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:00:26.845013Z","caller":"traceutil/trace.go:171","msg":"trace[1077959320] transaction","detail":"{read_only:false; response_revision:2537; number_of_response:1; }","duration":"136.080395ms","start":"2026-07-17T12:00:26.708892Z","end":"2026-07-17T12:00:26.844973Z","steps":["trace[1077959320] 'process raft request' (duration: 135.484499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:00:26.84566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.323501ms","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-07-17T12:00:26.845741Z","caller":"traceutil/trace.go:171","msg":"trace[300595802] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2537; }","duration":"161.448212ms","start":"2026-07-17T12:00:26.684279Z","end":"2026-07-17T12:00:26.845728Z","steps":["trace[300595802] 'agreement among raft nodes before linearized reading' (duration: 160.666203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:00:27.417961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.055876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-17T12:00:27.418053Z","caller":"traceutil/trace.go:171","msg":"trace[2052950085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2538; }","duration":"284.187647ms","start":"2026-07-17T12:00:27.133846Z","end":"2026-07-17T12:00:27.418034Z","steps":["trace[2052950085] 'range keys from in-memory index tree' (duration: 283.795773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:00:27.418062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.053467ms","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":"warn","ts":"2026-07-17T12:00:27.418098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.010247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T12:00:27.418165Z","caller":"traceutil/trace.go:171","msg":"trace[986350624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2538; }","duration":"282.200578ms","start":"2026-07-17T12:00:27.135945Z","end":"2026-07-17T12:00:27.418146Z","steps":["trace[986350624] 'range keys from in-memory index tree' (duration: 281.891175ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:00:27.418196Z","caller":"traceutil/trace.go:171","msg":"trace[492567874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2538; }","duration":"293.153108ms","start":"2026-07-17T12:00:27.125025Z","end":"2026-07-17T12:00:27.418178Z","steps":["trace[492567874] 'range keys from in-memory index tree' (duration: 292.836455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:00:27.418284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.909196ms","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-07-17T12:00:27.418362Z","caller":"traceutil/trace.go:171","msg":"trace[1602659458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2538; }","duration":"303.031668ms","start":"2026-07-17T12:00:27.115311Z","end":"2026-07-17T12:00:27.418343Z","steps":["trace[1602659458] 'range keys from in-memory index tree' (duration: 302.692384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:00:27.418407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:00:27.115293Z","time spent":"303.104548ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T12:00:27.735359Z","caller":"traceutil/trace.go:171","msg":"trace[1429496119] transaction","detail":"{read_only:false; response_revision:2539; number_of_response:1; }","duration":"313.776867ms","start":"2026-07-17T12:00:27.421551Z","end":"2026-07-17T12:00:27.735328Z","steps":["trace[1429496119] 'process raft request' (duration: 313.632295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:00:27.735694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:00:27.421521Z","time spent":"313.934058ms","remote":"127.0.0.1:37192","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-07-17T12:00:27.842577Z","caller":"traceutil/trace.go:171","msg":"trace[1612880617] transaction","detail":"{read_only:false; response_revision:2541; number_of_response:1; }","duration":"419.607984ms","start":"2026-07-17T12:00:27.422914Z","end":"2026-07-17T12:00:27.842522Z","steps":["trace[1612880617] 'process raft request' (duration: 419.525412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:00:27.84277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:00:27.422891Z","time spent":"419.778946ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T12:00:27.842575Z","caller":"traceutil/trace.go:171","msg":"trace[82076237] transaction","detail":"{read_only:false; response_revision:2540; number_of_response:1; }","duration":"419.885687ms","start":"2026-07-17T12:00:27.422662Z","end":"2026-07-17T12:00:27.842548Z","steps":["trace[82076237] 'process raft request' (duration: 406.28208ms)","trace[82076237] 'compare' (duration: 13.362264ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:00:27.842918Z","caller":"traceutil/trace.go:171","msg":"trace[1221747613] transaction","detail":"{read_only:false; response_revision:2542; number_of_response:1; }","duration":"419.893488ms","start":"2026-07-17T12:00:27.423014Z","end":"2026-07-17T12:00:27.842908Z","steps":["trace[1221747613] 'process raft request' (duration: 419.463933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:00:27.842977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:00:27.423004Z","time spent":"419.943308ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T12:00:27.842926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:00:27.42264Z","time spent":"420.19894ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T12:00:27.84307Z","caller":"traceutil/trace.go:171","msg":"trace[380129464] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"419.956028ms","start":"2026-07-17T12:00:27.423101Z","end":"2026-07-17T12:00:27.843057Z","steps":["trace[380129464] 'process raft request' (duration: 419.602175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:00:27.843212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:00:27.423081Z","time spent":"420.096129ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T12:01:35.225649Z","caller":"traceutil/trace.go:171","msg":"trace[478037364] transaction","detail":"{read_only:false; response_revision:3072; number_of_response:1; }","duration":"118.12018ms","start":"2026-07-17T12:01:35.107495Z","end":"2026-07-17T12:01:35.225615Z","steps":["trace[478037364] 'process raft request' (duration: 95.663539ms)","trace[478037364] 'compare' (duration: 22.327548ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:01:35.236246Z","caller":"traceutil/trace.go:171","msg":"trace[1569965330] transaction","detail":"{read_only:false; response_revision:3073; number_of_response:1; }","duration":"102.536146ms","start":"2026-07-17T12:01:35.133687Z","end":"2026-07-17T12:01:35.236223Z","steps":["trace[1569965330] 'process raft request' (duration: 102.040952ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:02:05.484845Z","caller":"traceutil/trace.go:171","msg":"trace[430396317] transaction","detail":"{read_only:false; response_revision:3273; number_of_response:1; }","duration":"103.998458ms","start":"2026-07-17T12:02:05.38083Z","end":"2026-07-17T12:02:05.484828Z","steps":["trace[430396317] 'process raft request' (duration: 103.967497ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:02:05.484997Z","caller":"traceutil/trace.go:171","msg":"trace[523126111] transaction","detail":"{read_only:false; response_revision:3271; number_of_response:1; }","duration":"106.305506ms","start":"2026-07-17T12:02:05.378676Z","end":"2026-07-17T12:02:05.484981Z","steps":["trace[523126111] 'process raft request' (duration: 75.132687ms)","trace[523126111] 'compare' (duration: 30.844491ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:02:05.485159Z","caller":"traceutil/trace.go:171","msg":"trace[1929653219] transaction","detail":"{read_only:false; response_revision:3272; number_of_response:1; }","duration":"105.341743ms","start":"2026-07-17T12:02:05.37976Z","end":"2026-07-17T12:02:05.485102Z","steps":["trace[1929653219] 'process raft request' (duration: 104.997654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:02:05.706996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.370718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-17T12:02:05.707062Z","caller":"traceutil/trace.go:171","msg":"trace[958772641] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3273; }","duration":"106.511431ms","start":"2026-07-17T12:02:05.600536Z","end":"2026-07-17T12:02:05.707047Z","steps":["trace[958772641] 'range keys from in-memory index tree' (duration: 106.205014ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:02:11.324215Z","caller":"traceutil/trace.go:171","msg":"trace[1014570971] transaction","detail":"{read_only:false; response_revision:3305; number_of_response:1; }","duration":"122.830673ms","start":"2026-07-17T12:02:11.20136Z","end":"2026-07-17T12:02:11.32419Z","steps":["trace[1014570971] 'process raft request' (duration: 122.70127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:04:44.277226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.105292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T12:04:44.277455Z","caller":"traceutil/trace.go:171","msg":"trace[226161637] transaction","detail":"{read_only:false; response_revision:4389; number_of_response:1; }","duration":"223.323559ms","start":"2026-07-17T12:04:44.05409Z","end":"2026-07-17T12:04:44.277414Z","steps":["trace[226161637] 'process raft request' (duration: 223.244528ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:04:44.277438Z","caller":"traceutil/trace.go:171","msg":"trace[1722264131] transaction","detail":"{read_only:false; response_revision:4388; number_of_response:1; }","duration":"228.256856ms","start":"2026-07-17T12:04:44.049147Z","end":"2026-07-17T12:04:44.277404Z","steps":["trace[1722264131] 'process raft request' (duration: 80.91006ms)","trace[1722264131] 'compare' (duration: 146.052905ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:04:44.277562Z","caller":"traceutil/trace.go:171","msg":"trace[1728899293] transaction","detail":"{read_only:false; response_revision:4390; number_of_response:1; }","duration":"222.007438ms","start":"2026-07-17T12:04:44.055546Z","end":"2026-07-17T12:04:44.277553Z","steps":["trace[1728899293] 'process raft request' (duration: 221.833936ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:04:44.277555Z","caller":"traceutil/trace.go:171","msg":"trace[532436204] linearizableReadLoop","detail":"{readStateIndex:4543; appliedIndex:4540; }","duration":"142.976538ms","start":"2026-07-17T12:04:44.134551Z","end":"2026-07-17T12:04:44.277527Z","steps":["trace[532436204] 'read index received' (duration: 125.400126ms)","trace[532436204] 'applied index is now lower than readState.Index' (duration: 17.573502ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T12:04:44.277653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.09279ms","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":"info","ts":"2026-07-17T12:04:44.277679Z","caller":"traceutil/trace.go:171","msg":"trace[2027984925] 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:4390; }","duration":"143.161561ms","start":"2026-07-17T12:04:44.134509Z","end":"2026-07-17T12:04:44.27767Z","steps":["trace[2027984925] 'agreement among raft nodes before linearized reading' (duration: 143.11357ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:04:44.827901Z","caller":"traceutil/trace.go:171","msg":"trace[755726476] transaction","detail":"{read_only:false; response_revision:4392; number_of_response:1; }","duration":"160.910394ms","start":"2026-07-17T12:04:44.66696Z","end":"2026-07-17T12:04:44.82787Z","steps":["trace[755726476] 'process raft request' (duration: 153.231976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:12.53405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422953109767004486,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T12:05:12.831711Z","caller":"traceutil/trace.go:171","msg":"trace[1917788719] transaction","detail":"{read_only:false; response_revision:4632; number_of_response:1; }","duration":"835.763282ms","start":"2026-07-17T12:05:11.995922Z","end":"2026-07-17T12:05:12.831685Z","steps":["trace[1917788719] 'process raft request' (duration: 834.060756ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:05:12.831735Z","caller":"traceutil/trace.go:171","msg":"trace[2018863318] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"453.378407ms","start":"2026-07-17T12:05:12.378329Z","end":"2026-07-17T12:05:12.831707Z","steps":["trace[2018863318] 'process raft request' (duration: 453.335276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:12.831834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:11.995902Z","time spent":"835.872343ms","remote":"127.0.0.1:37092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11614,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T12:05:12.831851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:12.378297Z","time spent":"453.496429ms","remote":"127.0.0.1:37192","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-07-17T12:05:12.831899Z","caller":"traceutil/trace.go:171","msg":"trace[2141587255] linearizableReadLoop","detail":"{readStateIndex:4815; appliedIndex:4811; }","duration":"797.913376ms","start":"2026-07-17T12:05:12.033946Z","end":"2026-07-17T12:05:12.83186Z","steps":["trace[2141587255] 'read index received' (duration: 795.915586ms)","trace[2141587255] 'applied index is now lower than readState.Index' (duration: 1.99709ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:05:12.831989Z","caller":"traceutil/trace.go:171","msg":"trace[1185268382] transaction","detail":"{read_only:false; response_revision:4634; number_of_response:1; }","duration":"461.828791ms","start":"2026-07-17T12:05:12.370087Z","end":"2026-07-17T12:05:12.831916Z","steps":["trace[1185268382] 'process raft request' (duration: 461.551557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:12.832144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:12.370062Z","time spent":"462.008494ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T12:05:12.832118Z","caller":"traceutil/trace.go:171","msg":"trace[1562754971] transaction","detail":"{read_only:false; response_revision:4633; number_of_response:1; }","duration":"786.56483ms","start":"2026-07-17T12:05:12.045209Z","end":"2026-07-17T12:05:12.831774Z","steps":["trace[1562754971] 'process raft request' (duration: 786.379327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:12.832201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.21891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" ","response":"range_response_count:1 size:8671"} {"level":"info","ts":"2026-07-17T12:05:12.832354Z","caller":"traceutil/trace.go:171","msg":"trace[81955077] range","detail":"{range_begin:/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db; range_end:; response_count:1; response_revision:4636; }","duration":"798.393642ms","start":"2026-07-17T12:05:12.033915Z","end":"2026-07-17T12:05:12.832308Z","steps":["trace[81955077] 'agreement among raft nodes before linearized reading' (duration: 798.051848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:12.832529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:12.033901Z","time spent":"798.593185ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":8694,"request content":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" "} {"level":"warn","ts":"2026-07-17T12:05:12.832691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:12.045193Z","time spent":"787.098857ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T12:05:13.493379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422953109767004509,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T12:05:13.994322Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14422953109767004509,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T12:05:14.340378Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.473158488s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T12:05:14.34758Z","caller":"traceutil/trace.go:171","msg":"trace[697313482] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4641; }","duration":"1.480452256s","start":"2026-07-17T12:05:12.867113Z","end":"2026-07-17T12:05:14.347566Z","steps":["trace[697313482] 'process raft request' (duration: 1.473490925s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.347586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089495343s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T12:05:14.347657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:12.867093Z","time spent":"1.480527717s","remote":"127.0.0.1:37262","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T12:05:14.347664Z","caller":"traceutil/trace.go:171","msg":"trace[1759141086] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4641; }","duration":"1.089584044s","start":"2026-07-17T12:05:13.258067Z","end":"2026-07-17T12:05:14.347651Z","steps":["trace[1759141086] 'range keys from in-memory index tree' (duration: 1.089479553s)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:05:14.348782Z","caller":"traceutil/trace.go:171","msg":"trace[213480349] linearizableReadLoop","detail":"{readStateIndex:4825; appliedIndex:4821; }","duration":"1.355901217s","start":"2026-07-17T12:05:12.99287Z","end":"2026-07-17T12:05:14.348772Z","steps":["trace[213480349] 'read index received' (duration: 1.347747519s)","trace[213480349] 'applied index is now lower than readState.Index' (duration: 8.153048ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:05:14.348974Z","caller":"traceutil/trace.go:171","msg":"trace[638243384] transaction","detail":"{read_only:false; response_revision:4642; number_of_response:1; }","duration":"1.388994104s","start":"2026-07-17T12:05:12.959968Z","end":"2026-07-17T12:05:14.348962Z","steps":["trace[638243384] 'process raft request' (duration: 1.388681408s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.349044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:12.959947Z","time spent":"1.389057985s","remote":"127.0.0.1:37192","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-07-17T12:05:14.349192Z","caller":"traceutil/trace.go:171","msg":"trace[1999460110] transaction","detail":"{read_only:false; response_revision:4643; number_of_response:1; }","duration":"522.964678ms","start":"2026-07-17T12:05:13.826217Z","end":"2026-07-17T12:05:14.349181Z","steps":["trace[1999460110] 'process raft request' (duration: 522.50423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.349253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:13.826197Z","time spent":"523.026299ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T12:05:14.349385Z","caller":"traceutil/trace.go:171","msg":"trace[193878634] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"402.831577ms","start":"2026-07-17T12:05:13.946543Z","end":"2026-07-17T12:05:14.349375Z","steps":["trace[193878634] 'process raft request' (duration: 402.205527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.34944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:13.946518Z","time spent":"402.895087ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":484,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T12:05:14.349658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356782621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/auth-system/keycloak-db-1\" ","response":"range_response_count:1 size:2364"} {"level":"info","ts":"2026-07-17T12:05:14.349694Z","caller":"traceutil/trace.go:171","msg":"trace[1087380858] range","detail":"{range_begin:/registry/persistentvolumeclaims/auth-system/keycloak-db-1; range_end:; response_count:1; response_revision:4644; }","duration":"1.356846092s","start":"2026-07-17T12:05:12.992839Z","end":"2026-07-17T12:05:14.349685Z","steps":["trace[1087380858] 'agreement among raft nodes before linearized reading' (duration: 1.356719s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.349721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:12.992825Z","time spent":"1.356888073s","remote":"127.0.0.1:37068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":2387,"request content":"key:\"/registry/persistentvolumeclaims/auth-system/keycloak-db-1\" "} {"level":"warn","ts":"2026-07-17T12:05:14.349865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264076412s","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-07-17T12:05:14.349901Z","caller":"traceutil/trace.go:171","msg":"trace[915920722] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:4644; }","duration":"1.264141322s","start":"2026-07-17T12:05:13.085749Z","end":"2026-07-17T12:05:14.34989Z","steps":["trace[915920722] 'agreement among raft nodes before linearized reading' (duration: 1.264090081s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.349931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:13.085729Z","time spent":"1.264190354s","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-17T12:05:14.350176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.283168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" ","response":"range_response_count:1 size:8671"} {"level":"info","ts":"2026-07-17T12:05:14.350259Z","caller":"traceutil/trace.go:171","msg":"trace[1755867521] range","detail":"{range_begin:/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db; range_end:; response_count:1; response_revision:4644; }","duration":"507.401319ms","start":"2026-07-17T12:05:13.842837Z","end":"2026-07-17T12:05:14.350238Z","steps":["trace[1755867521] 'agreement among raft nodes before linearized reading' (duration: 507.144925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.350299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:13.842824Z","time spent":"507.46669ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":8694,"request content":"key:\"/registry/postgresql.cnpg.io/clusters/auth-system/keycloak-db\" "} {"level":"warn","ts":"2026-07-17T12:05:14.350527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.259627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T12:05:14.350567Z","caller":"traceutil/trace.go:171","msg":"trace[1457448234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4644; }","duration":"550.339919ms","start":"2026-07-17T12:05:13.800217Z","end":"2026-07-17T12:05:14.350557Z","steps":["trace[1457448234] 'agreement among raft nodes before linearized reading' (duration: 550.280668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.350696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:13.800196Z","time spent":"550.44799ms","remote":"127.0.0.1:42728","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-07-17T12:05:14.350936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110272417s","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-07-17T12:05:14.350979Z","caller":"traceutil/trace.go:171","msg":"trace[330577850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4644; }","duration":"1.110331598s","start":"2026-07-17T12:05:13.240637Z","end":"2026-07-17T12:05:14.350969Z","steps":["trace[330577850] 'agreement among raft nodes before linearized reading' (duration: 1.110233216s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.351008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:13.240628Z","time spent":"1.110373418s","remote":"127.0.0.1:37192","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-07-17T12:05:14.351004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008563076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T12:05:14.351037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109462725s","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-07-17T12:05:14.351077Z","caller":"traceutil/trace.go:171","msg":"trace[1186708579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4644; }","duration":"1.109544056s","start":"2026-07-17T12:05:13.241524Z","end":"2026-07-17T12:05:14.351068Z","steps":["trace[1186708579] 'agreement among raft nodes before linearized reading' (duration: 1.109439075s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.351126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:13.241504Z","time spent":"1.109613968s","remote":"127.0.0.1:37192","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-07-17T12:05:14.351079Z","caller":"traceutil/trace.go:171","msg":"trace[1251430826] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:4644; }","duration":"1.008668647s","start":"2026-07-17T12:05:13.342391Z","end":"2026-07-17T12:05:14.35106Z","steps":["trace[1251430826] 'agreement among raft nodes before linearized reading' (duration: 1.008559155s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.351126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129447728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-17T12:05:14.351202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.778009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-17T12:05:14.351253Z","caller":"traceutil/trace.go:171","msg":"trace[379321379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4644; }","duration":"774.85617ms","start":"2026-07-17T12:05:13.576386Z","end":"2026-07-17T12:05:14.351242Z","steps":["trace[379321379] 'agreement among raft nodes before linearized reading' (duration: 774.722658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.351216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:13.342375Z","time spent":"1.0088264s","remote":"127.0.0.1:41850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-17T12:05:14.351277Z","caller":"traceutil/trace.go:171","msg":"trace[941262528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4644; }","duration":"1.1295749s","start":"2026-07-17T12:05:13.221634Z","end":"2026-07-17T12:05:14.351209Z","steps":["trace[941262528] 'agreement among raft nodes before linearized reading' (duration: 1.129327366s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.351301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:13.576368Z","time spent":"774.92203ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T12:05:14.350941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112575551s","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-07-17T12:05:14.351386Z","caller":"traceutil/trace.go:171","msg":"trace[1406019242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4644; }","duration":"1.113061447s","start":"2026-07-17T12:05:13.238315Z","end":"2026-07-17T12:05:14.351376Z","steps":["trace[1406019242] 'agreement among raft nodes before linearized reading' (duration: 1.11256291s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:05:14.351423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:13.238294Z","time spent":"1.113119569s","remote":"127.0.0.1:37192","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-07-17T12:05:14.351788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T12:05:13.221613Z","time spent":"1.129690561s","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T12:06:48.981739Z","caller":"traceutil/trace.go:171","msg":"trace[1597598222] linearizableReadLoop","detail":"{readStateIndex:5529; appliedIndex:5528; }","duration":"196.933261ms","start":"2026-07-17T12:06:48.784782Z","end":"2026-07-17T12:06:48.981715Z","steps":["trace[1597598222] 'read index received' (duration: 195.106006ms)","trace[1597598222] 'applied index is now lower than readState.Index' (duration: 1.826305ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:06:48.981969Z","caller":"traceutil/trace.go:171","msg":"trace[1089130186] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"292.108061ms","start":"2026-07-17T12:06:48.68983Z","end":"2026-07-17T12:06:48.981938Z","steps":["trace[1089130186] 'process raft request' (duration: 290.105345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:06:48.982507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.610013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T12:06:48.982568Z","caller":"traceutil/trace.go:171","msg":"trace[117979498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5322; }","duration":"182.715754ms","start":"2026-07-17T12:06:48.799837Z","end":"2026-07-17T12:06:48.982553Z","steps":["trace[117979498] 'agreement among raft nodes before linearized reading' (duration: 182.616663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:06:48.982727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.382571ms","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-07-17T12:06:48.982809Z","caller":"traceutil/trace.go:171","msg":"trace[639645349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5322; }","duration":"143.514382ms","start":"2026-07-17T12:06:48.839277Z","end":"2026-07-17T12:06:48.982792Z","steps":["trace[639645349] 'agreement among raft nodes before linearized reading' (duration: 143.270759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:06:48.982834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.056749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-17T12:06:48.983157Z","caller":"traceutil/trace.go:171","msg":"trace[740262888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5322; }","duration":"147.396892ms","start":"2026-07-17T12:06:48.835727Z","end":"2026-07-17T12:06:48.983124Z","steps":["trace[740262888] 'agreement among raft nodes before linearized reading' (duration: 147.012138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:06:48.982907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.649911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-17T12:06:48.983197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.492252ms","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":"info","ts":"2026-07-17T12:06:48.983241Z","caller":"traceutil/trace.go:171","msg":"trace[1266542110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5322; }","duration":"169.010156ms","start":"2026-07-17T12:06:48.814213Z","end":"2026-07-17T12:06:48.983224Z","steps":["trace[1266542110] 'agreement among raft nodes before linearized reading' (duration: 168.642231ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:06:48.983346Z","caller":"traceutil/trace.go:171","msg":"trace[2118311609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5322; }","duration":"143.713025ms","start":"2026-07-17T12:06:48.839583Z","end":"2026-07-17T12:06:48.983296Z","steps":["trace[2118311609] 'agreement among raft nodes before linearized reading' (duration: 143.402711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:06:48.983864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.524511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-17T12:06:48.983987Z","caller":"traceutil/trace.go:171","msg":"trace[344106523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5322; }","duration":"199.19678ms","start":"2026-07-17T12:06:48.784731Z","end":"2026-07-17T12:06:48.983928Z","steps":["trace[344106523] 'agreement among raft nodes before linearized reading' (duration: 197.501928ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:06:53.367447Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3196} {"level":"info","ts":"2026-07-17T12:06:53.459347Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3196,"took":"90.648817ms","hash":2332943392,"current-db-size-bytes":42991616,"current-db-size":"43 MB","current-db-size-in-use-bytes":13533184,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-17T12:06:53.459413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2332943392,"revision":3196,"compact-revision":-1} {"level":"info","ts":"2026-07-17T12:09:53.455289Z","caller":"traceutil/trace.go:171","msg":"trace[308526885] transaction","detail":"{read_only:false; response_revision:7348; number_of_response:1; }","duration":"198.390353ms","start":"2026-07-17T12:09:53.256876Z","end":"2026-07-17T12:09:53.455267Z","steps":["trace[308526885] 'process raft request' (duration: 198.255889ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:09:53.611434Z","caller":"traceutil/trace.go:171","msg":"trace[319143633] transaction","detail":"{read_only:false; response_revision:7349; number_of_response:1; }","duration":"291.918596ms","start":"2026-07-17T12:09:53.319488Z","end":"2026-07-17T12:09:53.611407Z","steps":["trace[319143633] 'process raft request' (duration: 274.062959ms)","trace[319143633] 'compare' (duration: 17.29491ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:09:53.611615Z","caller":"traceutil/trace.go:171","msg":"trace[446098738] linearizableReadLoop","detail":"{readStateIndex:7622; appliedIndex:7619; }","duration":"218.191679ms","start":"2026-07-17T12:09:53.393399Z","end":"2026-07-17T12:09:53.611591Z","steps":["trace[446098738] 'read index received' (duration: 61.740896ms)","trace[446098738] 'applied index is now lower than readState.Index' (duration: 156.449563ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:09:53.611731Z","caller":"traceutil/trace.go:171","msg":"trace[945863441] transaction","detail":"{read_only:false; response_revision:7350; number_of_response:1; }","duration":"219.312322ms","start":"2026-07-17T12:09:53.392408Z","end":"2026-07-17T12:09:53.61172Z","steps":["trace[945863441] 'process raft request' (duration: 218.965232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:09:53.611929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.511318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-07-17T12:09:53.611979Z","caller":"traceutil/trace.go:171","msg":"trace[2004395000] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:7350; }","duration":"218.581621ms","start":"2026-07-17T12:09:53.393385Z","end":"2026-07-17T12:09:53.611967Z","steps":["trace[2004395000] 'agreement among raft nodes before linearized reading' (duration: 218.387895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:09:53.613979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.108132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-17T12:09:53.614046Z","caller":"traceutil/trace.go:171","msg":"trace[771035967] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:7351; }","duration":"150.214196ms","start":"2026-07-17T12:09:53.463815Z","end":"2026-07-17T12:09:53.61403Z","steps":["trace[771035967] 'agreement among raft nodes before linearized reading' (duration: 150.01884ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:09:53.988228Z","caller":"traceutil/trace.go:171","msg":"trace[1263901827] transaction","detail":"{read_only:false; response_revision:7366; number_of_response:1; }","duration":"146.644109ms","start":"2026-07-17T12:09:53.841507Z","end":"2026-07-17T12:09:53.988152Z","steps":["trace[1263901827] 'process raft request' (duration: 111.701417ms)","trace[1263901827] 'compare' (duration: 34.647553ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:09:53.991134Z","caller":"traceutil/trace.go:171","msg":"trace[1764234073] linearizableReadLoop","detail":"{readStateIndex:7640; appliedIndex:7637; }","duration":"135.500354ms","start":"2026-07-17T12:09:53.855623Z","end":"2026-07-17T12:09:53.991124Z","steps":["trace[1764234073] 'read index received' (duration: 97.709177ms)","trace[1764234073] 'applied index is now lower than readState.Index' (duration: 37.790397ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:09:53.991248Z","caller":"traceutil/trace.go:171","msg":"trace[75111175] transaction","detail":"{read_only:false; response_revision:7368; number_of_response:1; }","duration":"145.194365ms","start":"2026-07-17T12:09:53.846017Z","end":"2026-07-17T12:09:53.991211Z","steps":["trace[75111175] 'process raft request' (duration: 145.080272ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:09:53.991298Z","caller":"traceutil/trace.go:171","msg":"trace[1134558274] transaction","detail":"{read_only:false; response_revision:7367; number_of_response:1; }","duration":"145.880456ms","start":"2026-07-17T12:09:53.8453Z","end":"2026-07-17T12:09:53.991181Z","steps":["trace[1134558274] 'process raft request' (duration: 145.746722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:09:53.991387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.76044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/postgresql.cnpg.io/databases/\" range_end:\"/registry/postgresql.cnpg.io/databases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T12:09:53.991418Z","caller":"traceutil/trace.go:171","msg":"trace[1460860348] range","detail":"{range_begin:/registry/postgresql.cnpg.io/databases/; range_end:/registry/postgresql.cnpg.io/databases0; response_count:0; response_revision:7368; }","duration":"123.834162ms","start":"2026-07-17T12:09:53.867572Z","end":"2026-07-17T12:09:53.991407Z","steps":["trace[1460860348] 'agreement among raft nodes before linearized reading' (duration: 123.793551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:09:53.991581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.326237ms","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-07-17T12:09:53.991605Z","caller":"traceutil/trace.go:171","msg":"trace[52681247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7368; }","duration":"123.380389ms","start":"2026-07-17T12:09:53.868219Z","end":"2026-07-17T12:09:53.991599Z","steps":["trace[52681247] 'agreement among raft nodes before linearized reading' (duration: 123.320957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:09:53.991339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.70003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-17T12:09:53.991647Z","caller":"traceutil/trace.go:171","msg":"trace[2013840542] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7368; }","duration":"136.03727ms","start":"2026-07-17T12:09:53.855594Z","end":"2026-07-17T12:09:53.991631Z","steps":["trace[2013840542] 'agreement among raft nodes before linearized reading' (duration: 135.603657ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:10:06.234992Z","caller":"traceutil/trace.go:171","msg":"trace[522701231] linearizableReadLoop","detail":"{readStateIndex:7830; appliedIndex:7829; }","duration":"102.349323ms","start":"2026-07-17T12:10:06.132614Z","end":"2026-07-17T12:10:06.234963Z","steps":["trace[522701231] 'read index received' (duration: 44.194162ms)","trace[522701231] 'applied index is now lower than readState.Index' (duration: 58.154041ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:10:06.235328Z","caller":"traceutil/trace.go:171","msg":"trace[2136401143] transaction","detail":"{read_only:false; response_revision:7552; number_of_response:1; }","duration":"103.173727ms","start":"2026-07-17T12:10:06.132137Z","end":"2026-07-17T12:10:06.235311Z","steps":["trace[2136401143] 'process raft request' (duration: 44.683497ms)","trace[2136401143] 'compare' (duration: 57.999745ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T12:10:06.235461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.581191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T12:10:06.235549Z","caller":"traceutil/trace.go:171","msg":"trace[1471792337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7552; }","duration":"101.693055ms","start":"2026-07-17T12:10:06.133834Z","end":"2026-07-17T12:10:06.235527Z","steps":["trace[1471792337] 'agreement among raft nodes before linearized reading' (duration: 101.485928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:10:06.235828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.87891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T12:10:06.235866Z","caller":"traceutil/trace.go:171","msg":"trace[1570192748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7552; }","duration":"101.948392ms","start":"2026-07-17T12:10:06.133909Z","end":"2026-07-17T12:10:06.235857Z","steps":["trace[1570192748] 'agreement among raft nodes before linearized reading' (duration: 101.85536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:10:06.235888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.237879ms","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-07-17T12:10:06.235998Z","caller":"traceutil/trace.go:171","msg":"trace[1112545338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7552; }","duration":"103.388694ms","start":"2026-07-17T12:10:06.132593Z","end":"2026-07-17T12:10:06.235981Z","steps":["trace[1112545338] 'agreement among raft nodes before linearized reading' (duration: 103.160607ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:11:53.37374Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5356} {"level":"info","ts":"2026-07-17T12:11:53.431549Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5356,"took":"55.962589ms","hash":3489539841,"current-db-size-bytes":42991616,"current-db-size":"43 MB","current-db-size-in-use-bytes":31682560,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-17T12:11:53.431643Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3489539841,"revision":5356,"compact-revision":3196} {"level":"info","ts":"2026-07-17T12:13:46.699248Z","caller":"traceutil/trace.go:171","msg":"trace[941274888] transaction","detail":"{read_only:false; response_revision:9359; number_of_response:1; }","duration":"246.840882ms","start":"2026-07-17T12:13:46.452377Z","end":"2026-07-17T12:13:46.699218Z","steps":["trace[941274888] 'process raft request' (duration: 224.261185ms)","trace[941274888] 'compare' (duration: 22.447385ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:13:46.774728Z","caller":"traceutil/trace.go:171","msg":"trace[750877489] transaction","detail":"{read_only:false; response_revision:9360; number_of_response:1; }","duration":"278.397312ms","start":"2026-07-17T12:13:46.496305Z","end":"2026-07-17T12:13:46.774703Z","steps":["trace[750877489] 'process raft request' (duration: 278.213809ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:13:46.774923Z","caller":"traceutil/trace.go:171","msg":"trace[336742632] linearizableReadLoop","detail":"{readStateIndex:9691; appliedIndex:9689; }","duration":"268.88952ms","start":"2026-07-17T12:13:46.505987Z","end":"2026-07-17T12:13:46.774877Z","steps":["trace[336742632] 'read index received' (duration: 170.612768ms)","trace[336742632] 'applied index is now lower than readState.Index' (duration: 98.274782ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T12:13:46.77507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.059523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T12:13:46.775109Z","caller":"traceutil/trace.go:171","msg":"trace[330184372] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:9361; }","duration":"269.187515ms","start":"2026-07-17T12:13:46.505911Z","end":"2026-07-17T12:13:46.775099Z","steps":["trace[330184372] 'agreement among raft nodes before linearized reading' (duration: 269.086953ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:13:46.775636Z","caller":"traceutil/trace.go:171","msg":"trace[1223314083] transaction","detail":"{read_only:false; response_revision:9361; number_of_response:1; }","duration":"185.03035ms","start":"2026-07-17T12:13:46.589884Z","end":"2026-07-17T12:13:46.774914Z","steps":["trace[1223314083] 'process raft request' (duration: 184.779135ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:14:26.84698Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3490cdb1ff3fc828","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T12:14:26.853329Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-17T12:14:26.85341Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-17T12:15:48.328074Z","caller":"traceutil/trace.go:171","msg":"trace[1724432488] transaction","detail":"{read_only:false; response_revision:10175; number_of_response:1; }","duration":"288.056289ms","start":"2026-07-17T12:15:48.039991Z","end":"2026-07-17T12:15:48.328048Z","steps":["trace[1724432488] 'process raft request' (duration: 287.888696ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:15:48.329892Z","caller":"traceutil/trace.go:171","msg":"trace[1519261007] linearizableReadLoop","detail":"{readStateIndex:10535; appliedIndex:10533; }","duration":"244.793869ms","start":"2026-07-17T12:15:48.085075Z","end":"2026-07-17T12:15:48.329869Z","steps":["trace[1519261007] 'read index received' (duration: 242.782774ms)","trace[1519261007] 'applied index is now lower than readState.Index' (duration: 2.009505ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T12:15:48.329965Z","caller":"traceutil/trace.go:171","msg":"trace[913039922] transaction","detail":"{read_only:false; response_revision:10176; number_of_response:1; }","duration":"286.713933ms","start":"2026-07-17T12:15:48.043213Z","end":"2026-07-17T12:15:48.329927Z","steps":["trace[913039922] 'process raft request' (duration: 286.52174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T12:15:48.330133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.304345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-17T12:15:48.330152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.024893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T12:15:48.330177Z","caller":"traceutil/trace.go:171","msg":"trace[809976254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10176; }","duration":"242.402507ms","start":"2026-07-17T12:15:48.08776Z","end":"2026-07-17T12:15:48.330163Z","steps":["trace[809976254] 'agreement among raft nodes before linearized reading' (duration: 242.231314ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:15:48.330221Z","caller":"traceutil/trace.go:171","msg":"trace[806962875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10176; }","duration":"245.178306ms","start":"2026-07-17T12:15:48.085023Z","end":"2026-07-17T12:15:48.330202Z","steps":["trace[806962875] 'agreement among raft nodes before linearized reading' (duration: 244.946032ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T12:16:53.37934Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8471} {"level":"info","ts":"2026-07-17T12:16:53.548792Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8471,"took":"167.666275ms","hash":4142371943,"current-db-size-bytes":42991616,"current-db-size":"43 MB","current-db-size-in-use-bytes":19017728,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-17T12:16:53.548841Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4142371943,"revision":8471,"compact-revision":5356} {"level":"warn","ts":"2026-07-17T12:17:51.426874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.02952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-9thgh\" ","response":"range_response_count:1 size:4901"} {"level":"info","ts":"2026-07-17T12:17:51.426952Z","caller":"traceutil/trace.go:171","msg":"trace[1493074248] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-9thgh; range_end:; response_count:1; response_revision:10918; }","duration":"103.147313ms","start":"2026-07-17T12:17:51.323788Z","end":"2026-07-17T12:17:51.426936Z","steps":["trace[1493074248] 'range keys from in-memory index tree' (duration: 102.891898ms)"],"step_count":1}