{"level":"warn","ts":"2026-04-19T13:29:44.30447Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-19T13:29:44.304547Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-04-19T13:29:44.304607Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-19T13:29:44.30462Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-04-19T13:29:44.304635Z","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-04-19T13:29:44.305254Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-04-19T13:29:44.305354Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-04-19T13:29:44.312594Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.05766ms"} {"level":"info","ts":"2026-04-19T13:29:44.334241Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-04-19T13:29:44.334356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-04-19T13:29:44.334389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-04-19T13:29:44.334409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-19T13:29:44.334428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-04-19T13:29:44.334486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-04-19T13:29:44.347868Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-19T13:29:44.351253Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-19T13:29:44.357449Z","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-04-19T13:29:44.367875Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-19T13:29:44.368118Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-19T13:29:44.368145Z","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-04-19T13:29:44.368254Z","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-04-19T13:29:44.368265Z","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-04-19T13:29:44.372161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-04-19T13:29:44.37234Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-04-19T13:29:44.374127Z","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-04-19T13:29:44.374291Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-04-19T13:29:44.374334Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-04-19T13:29:44.375689Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-19T13:29:44.375633Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-19T13:29:45.136744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-04-19T13:29:45.136825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-19T13:29:45.136867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-04-19T13:29:45.136888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-04-19T13:29:45.136899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-04-19T13:29:45.136916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-04-19T13:29:45.13693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-04-19T13:29:45.188102Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-04-19T13:29:45.188147Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:29:45.188111Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:29:45.188152Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:29:45.188507Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-19T13:29:45.188553Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-19T13:29:45.189946Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-04-19T13:29:45.190428Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-19T13:29:45.194113Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:29:45.194184Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:29:45.194213Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:30:01.031441Z","caller":"traceutil/trace.go:171","msg":"trace[1536838986] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"128.102709ms","start":"2026-04-19T13:30:00.903305Z","end":"2026-04-19T13:30:01.031407Z","steps":["trace[1536838986] 'process raft request' (duration: 127.905975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:01.284561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.111809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:30:01.284683Z","caller":"traceutil/trace.go:171","msg":"trace[1733828217] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"133.335443ms","start":"2026-04-19T13:30:01.151331Z","end":"2026-04-19T13:30:01.284666Z","steps":["trace[1733828217] 'read index received' (duration: 36.381µs)","trace[1733828217] 'applied index is now lower than readState.Index' (duration: 133.297722ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:30:01.284713Z","caller":"traceutil/trace.go:171","msg":"trace[84060627] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"217.077992ms","start":"2026-04-19T13:30:01.067613Z","end":"2026-04-19T13:30:01.284691Z","steps":["trace[84060627] 'process raft request' (duration: 83.100036ms)","trace[84060627] 'compare' (duration: 132.919145ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:30:01.284743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.413944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:30:01.284797Z","caller":"traceutil/trace.go:171","msg":"trace[338075380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"133.505366ms","start":"2026-04-19T13:30:01.151283Z","end":"2026-04-19T13:30:01.284789Z","steps":["trace[338075380] 'agreement among raft nodes before linearized reading' (duration: 133.429155ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:16.185775Z","caller":"traceutil/trace.go:171","msg":"trace[493229669] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"361.24504ms","start":"2026-04-19T13:30:15.824493Z","end":"2026-04-19T13:30:16.185738Z","steps":["trace[493229669] 'process raft request' (duration: 325.219475ms)","trace[493229669] 'compare' (duration: 35.691247ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:30:16.185932Z","caller":"traceutil/trace.go:171","msg":"trace[432623103] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"292.119811ms","start":"2026-04-19T13:30:15.893777Z","end":"2026-04-19T13:30:16.185897Z","steps":["trace[432623103] 'process raft request' (duration: 291.838094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:16.185954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:15.824457Z","time spent":"361.418844ms","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:30:16.185995Z","caller":"traceutil/trace.go:171","msg":"trace[1836854395] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"280.080341ms","start":"2026-04-19T13:30:15.905885Z","end":"2026-04-19T13:30:16.185966Z","steps":["trace[1836854395] 'process raft request' (duration: 279.835856ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:22.222472Z","caller":"traceutil/trace.go:171","msg":"trace[1480670884] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"108.601539ms","start":"2026-04-19T13:30:22.113844Z","end":"2026-04-19T13:30:22.222445Z","steps":["trace[1480670884] 'read index received' (duration: 108.436415ms)","trace[1480670884] 'applied index is now lower than readState.Index' (duration: 164.004µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:30:22.222607Z","caller":"traceutil/trace.go:171","msg":"trace[1143132843] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"387.68062ms","start":"2026-04-19T13:30:21.834894Z","end":"2026-04-19T13:30:22.222575Z","steps":["trace[1143132843] 'process raft request' (duration: 387.394145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:22.22275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.893664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2tvp2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-19T13:30:22.222794Z","caller":"traceutil/trace.go:171","msg":"trace[2044243890] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2tvp2; range_end:; response_count:1; response_revision:577; }","duration":"108.998656ms","start":"2026-04-19T13:30:22.113784Z","end":"2026-04-19T13:30:22.222783Z","steps":["trace[2044243890] 'agreement among raft nodes before linearized reading' (duration: 108.806373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:22.2228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:21.834871Z","time spent":"387.844744ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:30:43.643374Z","caller":"traceutil/trace.go:171","msg":"trace[824500038] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"104.904001ms","start":"2026-04-19T13:30:43.538444Z","end":"2026-04-19T13:30:43.643348Z","steps":["trace[824500038] 'read index received' (duration: 57.413574ms)","trace[824500038] 'applied index is now lower than readState.Index' (duration: 47.488627ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:30:43.643678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.217307ms","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-04-19T13:30:43.643747Z","caller":"traceutil/trace.go:171","msg":"trace[844467003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:757; }","duration":"105.34382ms","start":"2026-04-19T13:30:43.538387Z","end":"2026-04-19T13:30:43.643731Z","steps":["trace[844467003] 'agreement among raft nodes before linearized reading' (duration: 105.144576ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:11.99844Z","caller":"traceutil/trace.go:171","msg":"trace[617995421] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"209.580968ms","start":"2026-04-19T13:31:11.788833Z","end":"2026-04-19T13:31:11.998414Z","steps":["trace[617995421] 'process raft request' (duration: 209.401305ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:12.017947Z","caller":"traceutil/trace.go:171","msg":"trace[1105676740] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"226.601292ms","start":"2026-04-19T13:31:11.791317Z","end":"2026-04-19T13:31:12.017919Z","steps":["trace[1105676740] 'process raft request' (duration: 225.928777ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:31.465442Z","caller":"traceutil/trace.go:171","msg":"trace[487890581] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"113.977983ms","start":"2026-04-19T13:31:31.351434Z","end":"2026-04-19T13:31:31.465412Z","steps":["trace[487890581] 'process raft request' (duration: 113.813339ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:31.465523Z","caller":"traceutil/trace.go:171","msg":"trace[21881857] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"114.245648ms","start":"2026-04-19T13:31:31.351227Z","end":"2026-04-19T13:31:31.465473Z","steps":["trace[21881857] 'process raft request' (duration: 113.87891ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:34.871033Z","caller":"traceutil/trace.go:171","msg":"trace[1896301282] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"115.707166ms","start":"2026-04-19T13:31:34.755289Z","end":"2026-04-19T13:31:34.870996Z","steps":["trace[1896301282] 'process raft request' (duration: 37.764296ms)","trace[1896301282] 'compare' (duration: 77.665165ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:31:58.091651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.465752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ec12a58f-8481-4d5d-9386-56a9fcb4b1bf\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-19T13:31:58.091767Z","caller":"traceutil/trace.go:171","msg":"trace[625273058] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ec12a58f-8481-4d5d-9386-56a9fcb4b1bf; range_end:; response_count:1; response_revision:1833; }","duration":"101.649396ms","start":"2026-04-19T13:31:57.99009Z","end":"2026-04-19T13:31:58.09174Z","steps":["trace[625273058] 'range keys from in-memory index tree' (duration: 101.239598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:42.055413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.60215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:32:42.055647Z","caller":"traceutil/trace.go:171","msg":"trace[1476656348] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"158.654557ms","start":"2026-04-19T13:32:41.896966Z","end":"2026-04-19T13:32:42.055621Z","steps":["trace[1476656348] 'process raft request' (duration: 41.716921ms)","trace[1476656348] 'compare' (duration: 116.416396ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:32:48.199117Z","caller":"traceutil/trace.go:171","msg":"trace[1703671356] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"111.193238ms","start":"2026-04-19T13:32:48.087888Z","end":"2026-04-19T13:32:48.199081Z","steps":["trace[1703671356] 'process raft request' (duration: 110.953223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:48.49144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.890912ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-19T13:32:48.491568Z","caller":"traceutil/trace.go:171","msg":"trace[1776820174] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2236; }","duration":"180.856158ms","start":"2026-04-19T13:32:48.310695Z","end":"2026-04-19T13:32:48.491551Z","steps":["trace[1776820174] 'read index received' (duration: 40.753742ms)","trace[1776820174] 'applied index is now lower than readState.Index' (duration: 140.100096ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:32:48.491714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.016091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:32:48.491751Z","caller":"traceutil/trace.go:171","msg":"trace[1659126991] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2153; }","duration":"181.125403ms","start":"2026-04-19T13:32:48.310614Z","end":"2026-04-19T13:32:48.49174Z","steps":["trace[1659126991] 'agreement among raft nodes before linearized reading' (duration: 181.032342ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:53.772753Z","caller":"traceutil/trace.go:171","msg":"trace[625584064] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"167.794101ms","start":"2026-04-19T13:32:53.60492Z","end":"2026-04-19T13:32:53.772714Z","steps":["trace[625584064] 'process raft request' (duration: 167.426254ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:34:19.286656Z","caller":"traceutil/trace.go:171","msg":"trace[1897717042] linearizableReadLoop","detail":"{readStateIndex:2947; appliedIndex:2946; }","duration":"136.482879ms","start":"2026-04-19T13:34:19.150147Z","end":"2026-04-19T13:34:19.28663Z","steps":["trace[1897717042] 'read index received' (duration: 109.06785ms)","trace[1897717042] 'applied index is now lower than readState.Index' (duration: 27.413439ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:34:19.286778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.608121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:34:19.286802Z","caller":"traceutil/trace.go:171","msg":"trace[1734749617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2837; }","duration":"136.683314ms","start":"2026-04-19T13:34:19.150112Z","end":"2026-04-19T13:34:19.286795Z","steps":["trace[1734749617] 'agreement among raft nodes before linearized reading' (duration: 136.612272ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:34:19.286999Z","caller":"traceutil/trace.go:171","msg":"trace[298774091] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"160.891174ms","start":"2026-04-19T13:34:19.12604Z","end":"2026-04-19T13:34:19.286931Z","steps":["trace[298774091] 'process raft request' (duration: 133.151627ms)","trace[298774091] 'compare' (duration: 27.173925ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:38:13.694784Z","caller":"traceutil/trace.go:171","msg":"trace[2079806215] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4869; }","duration":"102.900752ms","start":"2026-04-19T13:38:13.591849Z","end":"2026-04-19T13:38:13.69475Z","steps":["trace[2079806215] 'process raft request' (duration: 45.40462ms)","trace[2079806215] 'compare' (duration: 57.37367ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:38:19.093149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.246008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:38:19.093351Z","caller":"traceutil/trace.go:171","msg":"trace[96108881] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"235.562134ms","start":"2026-04-19T13:38:18.857707Z","end":"2026-04-19T13:38:19.093269Z","steps":["trace[96108881] 'process raft request' (duration: 118.742035ms)","trace[96108881] 'compare' (duration: 115.924672ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:38:19.106427Z","caller":"traceutil/trace.go:171","msg":"trace[296212111] linearizableReadLoop","detail":"{readStateIndex:5243; appliedIndex:5240; }","duration":"149.256648ms","start":"2026-04-19T13:38:18.957153Z","end":"2026-04-19T13:38:19.106409Z","steps":["trace[296212111] 'read index received' (duration: 19.311687ms)","trace[296212111] 'applied index is now lower than readState.Index' (duration: 129.942071ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:38:19.106554Z","caller":"traceutil/trace.go:171","msg":"trace[2628746] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"159.64829ms","start":"2026-04-19T13:38:18.946842Z","end":"2026-04-19T13:38:19.10649Z","steps":["trace[2628746] 'process raft request' (duration: 159.420646ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:38:19.10664Z","caller":"traceutil/trace.go:171","msg":"trace[157069549] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"155.732127ms","start":"2026-04-19T13:38:18.950875Z","end":"2026-04-19T13:38:19.106607Z","steps":["trace[157069549] 'process raft request' (duration: 155.494503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:19.106706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.538033ms","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-04-19T13:38:19.106743Z","caller":"traceutil/trace.go:171","msg":"trace[737731544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5052; }","duration":"149.629865ms","start":"2026-04-19T13:38:18.957102Z","end":"2026-04-19T13:38:19.106732Z","steps":["trace[737731544] 'agreement among raft nodes before linearized reading' (duration: 149.37225ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:38:28.823959Z","caller":"traceutil/trace.go:171","msg":"trace[547902958] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"120.900444ms","start":"2026-04-19T13:38:28.703024Z","end":"2026-04-19T13:38:28.823925Z","steps":["trace[547902958] 'process raft request' (duration: 85.871187ms)","trace[547902958] 'compare' (duration: 34.853054ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:38:29.00111Z","caller":"traceutil/trace.go:171","msg":"trace[393899579] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"103.762637ms","start":"2026-04-19T13:38:28.897323Z","end":"2026-04-19T13:38:29.001085Z","steps":["trace[393899579] 'process raft request' (duration: 40.683721ms)","trace[393899579] 'compare' (duration: 62.916453ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:38:29.001124Z","caller":"traceutil/trace.go:171","msg":"trace[229634630] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"100.077429ms","start":"2026-04-19T13:38:28.901034Z","end":"2026-04-19T13:38:29.001111Z","steps":["trace[229634630] 'process raft request' (duration: 100.037209ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:38:29.001283Z","caller":"traceutil/trace.go:171","msg":"trace[1645882173] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"103.242158ms","start":"2026-04-19T13:38:28.898032Z","end":"2026-04-19T13:38:29.001274Z","steps":["trace[1645882173] 'process raft request' (duration: 102.992494ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:38:29.340138Z","caller":"traceutil/trace.go:171","msg":"trace[1204051550] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"200.981725ms","start":"2026-04-19T13:38:29.139111Z","end":"2026-04-19T13:38:29.340093Z","steps":["trace[1204051550] 'process raft request' (duration: 200.19815ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:38:29.341779Z","caller":"traceutil/trace.go:171","msg":"trace[1231861625] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"201.777948ms","start":"2026-04-19T13:38:29.139969Z","end":"2026-04-19T13:38:29.341747Z","steps":["trace[1231861625] 'process raft request' (duration: 201.549594ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:38:29.34191Z","caller":"traceutil/trace.go:171","msg":"trace[2124349861] linearizableReadLoop","detail":"{readStateIndex:5456; appliedIndex:5454; }","duration":"192.845053ms","start":"2026-04-19T13:38:29.149024Z","end":"2026-04-19T13:38:29.341869Z","steps":["trace[2124349861] 'read index received' (duration: 190.322007ms)","trace[2124349861] 'applied index is now lower than readState.Index' (duration: 2.520786ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:38:29.342176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.134209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:38:29.342273Z","caller":"traceutil/trace.go:171","msg":"trace[1324458764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5259; }","duration":"193.289012ms","start":"2026-04-19T13:38:29.14896Z","end":"2026-04-19T13:38:29.342249Z","steps":["trace[1324458764] 'agreement among raft nodes before linearized reading' (duration: 193.113718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:29.342404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.301214ms","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-04-19T13:38:29.342466Z","caller":"traceutil/trace.go:171","msg":"trace[266217868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5259; }","duration":"139.403457ms","start":"2026-04-19T13:38:29.203048Z","end":"2026-04-19T13:38:29.342451Z","steps":["trace[266217868] 'agreement among raft nodes before linearized reading' (duration: 139.175422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:29.342399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.302086ms","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-04-19T13:38:29.342872Z","caller":"traceutil/trace.go:171","msg":"trace[921735264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5259; }","duration":"184.843936ms","start":"2026-04-19T13:38:29.158013Z","end":"2026-04-19T13:38:29.342857Z","steps":["trace[921735264] 'agreement among raft nodes before linearized reading' (duration: 184.121443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:36.720374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.057281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-04-19T13:38:36.720469Z","caller":"traceutil/trace.go:171","msg":"trace[447747505] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5414; }","duration":"105.206384ms","start":"2026-04-19T13:38:36.615239Z","end":"2026-04-19T13:38:36.720446Z","steps":["trace[447747505] 'range keys from in-memory index tree' (duration: 104.881828ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:38:37.13279Z","caller":"traceutil/trace.go:171","msg":"trace[959023649] linearizableReadLoop","detail":"{readStateIndex:5620; appliedIndex:5616; }","duration":"378.564695ms","start":"2026-04-19T13:38:36.754181Z","end":"2026-04-19T13:38:37.132745Z","steps":["trace[959023649] 'read index received' (duration: 57.642245ms)","trace[959023649] 'applied index is now lower than readState.Index' (duration: 320.9215ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:38:37.132826Z","caller":"traceutil/trace.go:171","msg":"trace[1890630454] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"404.491354ms","start":"2026-04-19T13:38:36.728276Z","end":"2026-04-19T13:38:37.132767Z","steps":["trace[1890630454] 'process raft request' (duration: 404.386782ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:38:37.132897Z","caller":"traceutil/trace.go:171","msg":"trace[2078748839] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"405.36562ms","start":"2026-04-19T13:38:36.727374Z","end":"2026-04-19T13:38:37.13274Z","steps":["trace[2078748839] 'process raft request' (duration: 405.068404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:37.133005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:38:36.728269Z","time spent":"404.641936ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1606,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:38:37.132891Z","caller":"traceutil/trace.go:171","msg":"trace[1996460647] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"404.448363ms","start":"2026-04-19T13:38:36.7284Z","end":"2026-04-19T13:38:37.132848Z","steps":["trace[1996460647] 'process raft request' (duration: 404.305781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:37.133055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.85337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f0e4e980-d14f-4a77-a9a6-7fd8b2a95d7f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-19T13:38:37.133104Z","caller":"traceutil/trace.go:171","msg":"trace[1027112135] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f0e4e980-d14f-4a77-a9a6-7fd8b2a95d7f; range_end:; response_count:1; response_revision:5418; }","duration":"378.964532ms","start":"2026-04-19T13:38:36.754126Z","end":"2026-04-19T13:38:37.133091Z","steps":["trace[1027112135] 'agreement among raft nodes before linearized reading' (duration: 378.763499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:37.133125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:38:36.727361Z","time spent":"405.650876ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1414,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:38:37.133149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:38:36.754108Z","time spent":"379.031493ms","remote":"127.0.0.1:36608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f0e4e980-d14f-4a77-a9a6-7fd8b2a95d7f\" "} {"level":"warn","ts":"2026-04-19T13:38:37.133152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:38:36.728394Z","time spent":"404.700578ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1622,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:38:37.136737Z","caller":"traceutil/trace.go:171","msg":"trace[1380667861] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"201.886311ms","start":"2026-04-19T13:38:36.934802Z","end":"2026-04-19T13:38:37.136688Z","steps":["trace[1380667861] 'process raft request' (duration: 201.683617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:37.136793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.123534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-gateway-cf54cb88c-hfhs6\" ","response":"range_response_count:1 size:4760"} {"level":"info","ts":"2026-04-19T13:38:37.136965Z","caller":"traceutil/trace.go:171","msg":"trace[1424049890] range","detail":"{range_begin:/registry/pods/monitoring/loki-gateway-cf54cb88c-hfhs6; range_end:; response_count:1; response_revision:5420; }","duration":"334.294568ms","start":"2026-04-19T13:38:36.802635Z","end":"2026-04-19T13:38:37.13693Z","steps":["trace[1424049890] 'agreement among raft nodes before linearized reading' (duration: 334.028462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:37.137039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:38:36.802624Z","time spent":"334.385809ms","remote":"127.0.0.1:36608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":4784,"request content":"key:\"/registry/pods/monitoring/loki-gateway-cf54cb88c-hfhs6\" "} {"level":"info","ts":"2026-04-19T13:39:45.214084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2980} {"level":"info","ts":"2026-04-19T13:39:45.294853Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2980,"took":"78.46455ms","hash":2705567851,"current-db-size-bytes":58822656,"current-db-size":"59 MB","current-db-size-in-use-bytes":33849344,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-19T13:39:45.294917Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2705567851,"revision":2980,"compact-revision":-1} {"level":"info","ts":"2026-04-19T13:44:45.221455Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6061} {"level":"info","ts":"2026-04-19T13:44:45.320282Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6061,"took":"96.502644ms","hash":3084090559,"current-db-size-bytes":58822656,"current-db-size":"59 MB","current-db-size-in-use-bytes":23261184,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-19T13:44:45.320379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3084090559,"revision":6061,"compact-revision":2980} {"level":"warn","ts":"2026-04-19T13:44:54.796736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.847927ms","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-04-19T13:44:54.796858Z","caller":"traceutil/trace.go:171","msg":"trace[1196791356] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8311; }","duration":"210.020081ms","start":"2026-04-19T13:44:54.586816Z","end":"2026-04-19T13:44:54.796836Z","steps":["trace[1196791356] 'range keys from in-memory index tree' (duration: 209.642224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:44:55.653326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.712046ms","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-04-19T13:44:55.65341Z","caller":"traceutil/trace.go:171","msg":"trace[1582122016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8318; }","duration":"228.828847ms","start":"2026-04-19T13:44:55.42456Z","end":"2026-04-19T13:44:55.653389Z","steps":["trace[1582122016] 'range keys from in-memory index tree' (duration: 228.568183ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:44:56.627163Z","caller":"traceutil/trace.go:171","msg":"trace[184714747] transaction","detail":"{read_only:false; response_revision:8321; number_of_response:1; }","duration":"100.258997ms","start":"2026-04-19T13:44:56.526872Z","end":"2026-04-19T13:44:56.627131Z","steps":["trace[184714747] 'process raft request' (duration: 100.044023ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:44:56.936084Z","caller":"traceutil/trace.go:171","msg":"trace[663009619] transaction","detail":"{read_only:false; response_revision:8322; number_of_response:1; }","duration":"115.165949ms","start":"2026-04-19T13:44:56.820887Z","end":"2026-04-19T13:44:56.936053Z","steps":["trace[663009619] 'process raft request' (duration: 114.924594ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:23.828604Z","caller":"traceutil/trace.go:171","msg":"trace[1737749059] linearizableReadLoop","detail":"{readStateIndex:8826; appliedIndex:8825; }","duration":"280.759161ms","start":"2026-04-19T13:45:23.547811Z","end":"2026-04-19T13:45:23.82857Z","steps":["trace[1737749059] 'read index received' (duration: 254.302495ms)","trace[1737749059] 'applied index is now lower than readState.Index' (duration: 26.454716ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:45:23.829065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.21764ms","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-04-19T13:45:23.829137Z","caller":"traceutil/trace.go:171","msg":"trace[1620518091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8523; }","duration":"281.343934ms","start":"2026-04-19T13:45:23.547773Z","end":"2026-04-19T13:45:23.829117Z","steps":["trace[1620518091] 'agreement among raft nodes before linearized reading' (duration: 281.090028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:23.829432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.512298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:45:23.829513Z","caller":"traceutil/trace.go:171","msg":"trace[857455035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8523; }","duration":"238.63527ms","start":"2026-04-19T13:45:23.590859Z","end":"2026-04-19T13:45:23.829494Z","steps":["trace[857455035] 'agreement among raft nodes before linearized reading' (duration: 238.477537ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:36.84996Z","caller":"traceutil/trace.go:171","msg":"trace[1677101579] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"131.897905ms","start":"2026-04-19T13:45:36.718018Z","end":"2026-04-19T13:45:36.849916Z","steps":["trace[1677101579] 'process raft request' (duration: 125.046141ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:36.942334Z","caller":"traceutil/trace.go:171","msg":"trace[1133810546] transaction","detail":"{read_only:false; response_revision:8636; number_of_response:1; }","duration":"222.144517ms","start":"2026-04-19T13:45:36.720152Z","end":"2026-04-19T13:45:36.942296Z","steps":["trace[1133810546] 'process raft request' (duration: 221.853502ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:36.942593Z","caller":"traceutil/trace.go:171","msg":"trace[705177839] linearizableReadLoop","detail":"{readStateIndex:8943; appliedIndex:8941; }","duration":"203.527244ms","start":"2026-04-19T13:45:36.73903Z","end":"2026-04-19T13:45:36.942558Z","steps":["trace[705177839] 'read index received' (duration: 104.122003ms)","trace[705177839] 'applied index is now lower than readState.Index' (duration: 99.403571ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:45:36.942649Z","caller":"traceutil/trace.go:171","msg":"trace[375335729] transaction","detail":"{read_only:false; response_revision:8637; number_of_response:1; }","duration":"161.86221ms","start":"2026-04-19T13:45:36.780726Z","end":"2026-04-19T13:45:36.942588Z","steps":["trace[375335729] 'process raft request' (duration: 161.505853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:36.942906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.849371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6czvr\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-19T13:45:36.942989Z","caller":"traceutil/trace.go:171","msg":"trace[1953021804] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6czvr; range_end:; response_count:1; response_revision:8637; }","duration":"203.951563ms","start":"2026-04-19T13:45:36.739024Z","end":"2026-04-19T13:45:36.942976Z","steps":["trace[1953021804] 'agreement among raft nodes before linearized reading' (duration: 203.701088ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:38.017413Z","caller":"traceutil/trace.go:171","msg":"trace[766095823] linearizableReadLoop","detail":"{readStateIndex:8955; appliedIndex:8954; }","duration":"118.964124ms","start":"2026-04-19T13:45:37.898422Z","end":"2026-04-19T13:45:38.017386Z","steps":["trace[766095823] 'read index received' (duration: 118.76766ms)","trace[766095823] 'applied index is now lower than readState.Index' (duration: 194.994µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:45:38.017522Z","caller":"traceutil/trace.go:171","msg":"trace[1739052390] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"210.075382ms","start":"2026-04-19T13:45:37.807396Z","end":"2026-04-19T13:45:38.017472Z","steps":["trace[1739052390] 'process raft request' (duration: 209.804067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:38.017702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.657989ms","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-04-19T13:45:38.01774Z","caller":"traceutil/trace.go:171","msg":"trace[41011175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8648; }","duration":"113.744891ms","start":"2026-04-19T13:45:37.903984Z","end":"2026-04-19T13:45:38.017729Z","steps":["trace[41011175] 'agreement among raft nodes before linearized reading' (duration: 113.568728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:38.018325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.28819ms","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-04-19T13:45:38.018447Z","caller":"traceutil/trace.go:171","msg":"trace[2125147058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8648; }","duration":"120.047464ms","start":"2026-04-19T13:45:37.898372Z","end":"2026-04-19T13:45:38.01842Z","steps":["trace[2125147058] 'agreement among raft nodes before linearized reading' (duration: 119.178517ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:46:00.186594Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002400.wal"} {"level":"info","ts":"2026-04-19T13:46:53.453187Z","caller":"traceutil/trace.go:171","msg":"trace[2005605090] linearizableReadLoop","detail":"{readStateIndex:9710; appliedIndex:9709; }","duration":"123.616327ms","start":"2026-04-19T13:46:53.329527Z","end":"2026-04-19T13:46:53.453143Z","steps":["trace[2005605090] 'read index received' (duration: 123.399912ms)","trace[2005605090] 'applied index is now lower than readState.Index' (duration: 214.875µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:46:53.453299Z","caller":"traceutil/trace.go:171","msg":"trace[513611881] transaction","detail":"{read_only:false; response_revision:9384; number_of_response:1; }","duration":"235.733726ms","start":"2026-04-19T13:46:53.217523Z","end":"2026-04-19T13:46:53.453257Z","steps":["trace[513611881] 'process raft request' (duration: 235.382868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:46:53.453393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.83062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:46:53.453502Z","caller":"traceutil/trace.go:171","msg":"trace[2007732110] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:9384; }","duration":"124.031144ms","start":"2026-04-19T13:46:53.329454Z","end":"2026-04-19T13:46:53.453485Z","steps":["trace[2007732110] 'agreement among raft nodes before linearized reading' (duration: 123.84735ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:46:53.561142Z","caller":"traceutil/trace.go:171","msg":"trace[2008505699] transaction","detail":"{read_only:false; response_revision:9385; number_of_response:1; }","duration":"174.947372ms","start":"2026-04-19T13:46:53.386166Z","end":"2026-04-19T13:46:53.561113Z","steps":["trace[2008505699] 'process raft request' (duration: 128.59527ms)","trace[2008505699] 'compare' (duration: 46.174489ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:46:53.561343Z","caller":"traceutil/trace.go:171","msg":"trace[1928559441] transaction","detail":"{read_only:false; response_revision:9386; number_of_response:1; }","duration":"145.891525ms","start":"2026-04-19T13:46:53.415429Z","end":"2026-04-19T13:46:53.561321Z","steps":["trace[1928559441] 'process raft request' (duration: 145.64167ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:46:53.561446Z","caller":"traceutil/trace.go:171","msg":"trace[1393544812] linearizableReadLoop","detail":"{readStateIndex:9713; appliedIndex:9710; }","duration":"102.092411ms","start":"2026-04-19T13:46:53.459332Z","end":"2026-04-19T13:46:53.561424Z","steps":["trace[1393544812] 'read index received' (duration: 55.417533ms)","trace[1393544812] 'applied index is now lower than readState.Index' (duration: 46.673038ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:46:53.561525Z","caller":"traceutil/trace.go:171","msg":"trace[1404459205] transaction","detail":"{read_only:false; response_revision:9387; number_of_response:1; }","duration":"142.663954ms","start":"2026-04-19T13:46:53.418822Z","end":"2026-04-19T13:46:53.561486Z","steps":["trace[1404459205] 'process raft request' (duration: 142.421189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:46:53.561621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.269714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6czvr\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-19T13:46:53.56167Z","caller":"traceutil/trace.go:171","msg":"trace[62516012] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6czvr; range_end:; response_count:1; response_revision:9387; }","duration":"102.378466ms","start":"2026-04-19T13:46:53.459279Z","end":"2026-04-19T13:46:53.561658Z","steps":["trace[62516012] 'agreement among raft nodes before linearized reading' (duration: 102.215033ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:47:31.700942Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-19T13:47:31.709082Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-19T13:47:31.709349Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-19T13:49:45.22873Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8265} {"level":"info","ts":"2026-04-19T13:49:45.314688Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8265,"took":"83.068524ms","hash":3467331114,"current-db-size-bytes":58822656,"current-db-size":"59 MB","current-db-size-in-use-bytes":25817088,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-19T13:49:45.314795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3467331114,"revision":8265,"compact-revision":6061} {"level":"info","ts":"2026-04-19T13:54:45.23554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10655} {"level":"info","ts":"2026-04-19T13:54:45.31753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10655,"took":"79.537957ms","hash":617236351,"current-db-size-bytes":58822656,"current-db-size":"59 MB","current-db-size-in-use-bytes":20615168,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-19T13:54:45.317595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":617236351,"revision":10655,"compact-revision":8265}