{"level":"warn","ts":"2026-05-29T17:15:12.425363Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T17:15:12.425483Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.214.191: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.214.191:2380","--initial-cluster=instance=https://199.19.214.191:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.214.191:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.214.191:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-29T17:15:12.425575Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T17:15:12.425593Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.214.191:2380"]} {"level":"info","ts":"2026-05-29T17:15:12.425612Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T17:15:12.426278Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.214.191:2379"]} {"level":"info","ts":"2026-05-29T17:15:12.426355Z","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.214.191:2380"],"listen-peer-urls":["https://199.19.214.191:2380"],"advertise-client-urls":["https://199.19.214.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.214.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.214.191:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-29T17:15:12.431826Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.15503ms"} {"level":"info","ts":"2026-05-29T17:15:12.440511Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"97520c04ca496f8","cluster-id":"389a90fcb6f30643"} {"level":"info","ts":"2026-05-29T17:15:12.440608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"97520c04ca496f8 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T17:15:12.440638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"97520c04ca496f8 became follower at term 0"} {"level":"info","ts":"2026-05-29T17:15:12.440656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 97520c04ca496f8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T17:15:12.440683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"97520c04ca496f8 became follower at term 1"} {"level":"info","ts":"2026-05-29T17:15:12.440754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"97520c04ca496f8 switched to configuration voters=(681486928908162808)"} {"level":"warn","ts":"2026-05-29T17:15:12.450976Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T17:15:12.453616Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T17:15:12.455826Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-29T17:15:12.459438Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"97520c04ca496f8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T17:15:12.460318Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T17:15:12.460458Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T17:15:12.46109Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T17:15:12.460362Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"97520c04ca496f8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T17:15:12.462395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"97520c04ca496f8 switched to configuration voters=(681486928908162808)"} {"level":"info","ts":"2026-05-29T17:15:12.462675Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"389a90fcb6f30643","local-member-id":"97520c04ca496f8","added-peer-id":"97520c04ca496f8","added-peer-peer-urls":["https://199.19.214.191:2380"]} {"level":"info","ts":"2026-05-29T17:15:12.464326Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T17:15:12.464449Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.214.191:2380"} {"level":"info","ts":"2026-05-29T17:15:12.46447Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.214.191:2380"} {"level":"info","ts":"2026-05-29T17:15:12.464649Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"97520c04ca496f8","initial-advertise-peer-urls":["https://199.19.214.191:2380"],"listen-peer-urls":["https://199.19.214.191:2380"],"advertise-client-urls":["https://199.19.214.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.214.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T17:15:12.464698Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T17:15:13.441191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"97520c04ca496f8 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T17:15:13.44128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"97520c04ca496f8 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T17:15:13.441331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"97520c04ca496f8 received MsgPreVoteResp from 97520c04ca496f8 at term 1"} {"level":"info","ts":"2026-05-29T17:15:13.441353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"97520c04ca496f8 became candidate at term 2"} {"level":"info","ts":"2026-05-29T17:15:13.441385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"97520c04ca496f8 received MsgVoteResp from 97520c04ca496f8 at term 2"} {"level":"info","ts":"2026-05-29T17:15:13.441401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"97520c04ca496f8 became leader at term 2"} {"level":"info","ts":"2026-05-29T17:15:13.44142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 97520c04ca496f8 elected leader 97520c04ca496f8 at term 2"} {"level":"info","ts":"2026-05-29T17:15:13.446931Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T17:15:13.449353Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T17:15:13.449371Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"97520c04ca496f8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.214.191:2379]}","request-path":"/0/members/97520c04ca496f8/attributes","cluster-id":"389a90fcb6f30643","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T17:15:13.449604Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T17:15:13.453277Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T17:15:13.453359Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T17:15:13.453976Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"389a90fcb6f30643","local-member-id":"97520c04ca496f8","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T17:15:13.45437Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T17:15:13.45447Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T17:15:13.456216Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T17:15:13.457467Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.214.191:2379"} {"level":"info","ts":"2026-05-29T17:15:27.944874Z","caller":"traceutil/trace.go:171","msg":"trace[1456006038] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"116.556785ms","start":"2026-05-29T17:15:27.828287Z","end":"2026-05-29T17:15:27.944844Z","steps":["trace[1456006038] 'process raft request' (duration: 116.338417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:15:28.633515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.941365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-29T17:15:28.633665Z","caller":"traceutil/trace.go:171","msg":"trace[582120048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:409; }","duration":"304.151172ms","start":"2026-05-29T17:15:28.329485Z","end":"2026-05-29T17:15:28.633636Z","steps":["trace[582120048] 'range keys from in-memory index tree' (duration: 303.774769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:15:28.633732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:15:28.32945Z","time spent":"304.271916ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T17:15:28.844792Z","caller":"traceutil/trace.go:171","msg":"trace[1206173117] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"154.287384ms","start":"2026-05-29T17:15:28.690454Z","end":"2026-05-29T17:15:28.844741Z","steps":["trace[1206173117] 'read index received' (duration: 154.103758ms)","trace[1206173117] 'applied index is now lower than readState.Index' (duration: 182.396µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:15:28.845026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.547493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4796"} {"level":"info","ts":"2026-05-29T17:15:28.845062Z","caller":"traceutil/trace.go:171","msg":"trace[861156286] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:410; }","duration":"154.656157ms","start":"2026-05-29T17:15:28.690395Z","end":"2026-05-29T17:15:28.845052Z","steps":["trace[861156286] 'agreement among raft nodes before linearized reading' (duration: 154.502532ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:28.845199Z","caller":"traceutil/trace.go:171","msg":"trace[68387346] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"208.126791ms","start":"2026-05-29T17:15:28.637002Z","end":"2026-05-29T17:15:28.845129Z","steps":["trace[68387346] 'process raft request' (duration: 207.552681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:15:29.220632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.869687ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T17:15:29.220773Z","caller":"traceutil/trace.go:171","msg":"trace[2087188034] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:410; }","duration":"211.026952ms","start":"2026-05-29T17:15:29.009725Z","end":"2026-05-29T17:15:29.220752Z","steps":["trace[2087188034] 'range keys from in-memory index tree' (duration: 210.847245ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:29.22117Z","caller":"traceutil/trace.go:171","msg":"trace[1419705244] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"450.858203ms","start":"2026-05-29T17:15:28.770297Z","end":"2026-05-29T17:15:29.221155Z","steps":["trace[1419705244] 'process raft request' (duration: 439.883108ms)","trace[1419705244] 'compare' (duration: 10.859991ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:15:29.221902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:15:28.770242Z","time spent":"450.962037ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T17:15:29.223336Z","caller":"traceutil/trace.go:171","msg":"trace[1149090112] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"369.225931ms","start":"2026-05-29T17:15:28.85409Z","end":"2026-05-29T17:15:29.223315Z","steps":["trace[1149090112] 'process raft request' (duration: 368.782336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:15:29.22345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:15:28.854063Z","time spent":"369.322315ms","remote":"127.0.0.1:60262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T17:15:29.223672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.960597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T17:15:29.223774Z","caller":"traceutil/trace.go:171","msg":"trace[1607526632] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:421; }","duration":"264.306645ms","start":"2026-05-29T17:15:28.958684Z","end":"2026-05-29T17:15:29.22299Z","steps":["trace[1607526632] 'read index received' (duration: 251.694643ms)","trace[1607526632] 'applied index is now lower than readState.Index' (duration: 12.610782ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:15:29.223789Z","caller":"traceutil/trace.go:171","msg":"trace[1687365218] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"265.137174ms","start":"2026-05-29T17:15:28.958624Z","end":"2026-05-29T17:15:29.223761Z","steps":["trace[1687365218] 'agreement among raft nodes before linearized reading' (duration: 264.916376ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.098617Z","caller":"traceutil/trace.go:171","msg":"trace[945248497] transaction","detail":"{read_only:false; number_of_response:0; response_revision:555; }","duration":"119.565195ms","start":"2026-05-29T17:15:53.979033Z","end":"2026-05-29T17:15:54.098598Z","steps":["trace[945248497] 'process raft request' (duration: 119.527454ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.098686Z","caller":"traceutil/trace.go:171","msg":"trace[1757173791] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"125.24487ms","start":"2026-05-29T17:15:53.973397Z","end":"2026-05-29T17:15:54.098642Z","steps":["trace[1757173791] 'process raft request' (duration: 115.817534ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.104926Z","caller":"traceutil/trace.go:171","msg":"trace[1314017854] transaction","detail":"{read_only:false; number_of_response:0; response_revision:555; }","duration":"117.971568ms","start":"2026-05-29T17:15:53.98694Z","end":"2026-05-29T17:15:54.104912Z","steps":["trace[1314017854] 'process raft request' (duration: 116.91062ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.105039Z","caller":"traceutil/trace.go:171","msg":"trace[1479679709] transaction","detail":"{read_only:false; number_of_response:0; response_revision:555; }","duration":"117.984409ms","start":"2026-05-29T17:15:53.987049Z","end":"2026-05-29T17:15:54.105033Z","steps":["trace[1479679709] 'process raft request' (duration: 116.89124ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.105244Z","caller":"traceutil/trace.go:171","msg":"trace[1520835858] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"116.809377ms","start":"2026-05-29T17:15:53.988428Z","end":"2026-05-29T17:15:54.105237Z","steps":["trace[1520835858] 'process raft request' (duration: 115.559903ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.10532Z","caller":"traceutil/trace.go:171","msg":"trace[1441601248] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"116.819687ms","start":"2026-05-29T17:15:53.988494Z","end":"2026-05-29T17:15:54.105314Z","steps":["trace[1441601248] 'process raft request' (duration: 115.611925ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.105408Z","caller":"traceutil/trace.go:171","msg":"trace[1825584669] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"116.477767ms","start":"2026-05-29T17:15:53.988925Z","end":"2026-05-29T17:15:54.105403Z","steps":["trace[1825584669] 'process raft request' (duration: 115.322286ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.105492Z","caller":"traceutil/trace.go:171","msg":"trace[1014706485] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"116.416315ms","start":"2026-05-29T17:15:53.989069Z","end":"2026-05-29T17:15:54.105485Z","steps":["trace[1014706485] 'process raft request' (duration: 115.257505ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.105638Z","caller":"traceutil/trace.go:171","msg":"trace[1559507144] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"114.75981ms","start":"2026-05-29T17:15:53.990871Z","end":"2026-05-29T17:15:54.105631Z","steps":["trace[1559507144] 'process raft request' (duration: 113.546008ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.105806Z","caller":"traceutil/trace.go:171","msg":"trace[553385349] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"109.59737ms","start":"2026-05-29T17:15:53.9962Z","end":"2026-05-29T17:15:54.105798Z","steps":["trace[553385349] 'process raft request' (duration: 108.314156ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.105877Z","caller":"traceutil/trace.go:171","msg":"trace[1168510756] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"108.300834ms","start":"2026-05-29T17:15:53.99757Z","end":"2026-05-29T17:15:54.105871Z","steps":["trace[1168510756] 'process raft request' (duration: 107.003149ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.105971Z","caller":"traceutil/trace.go:171","msg":"trace[761416038] transaction","detail":"{read_only:false; number_of_response:1; response_revision:562; }","duration":"105.420614ms","start":"2026-05-29T17:15:54.000544Z","end":"2026-05-29T17:15:54.105965Z","steps":["trace[761416038] 'process raft request' (duration: 104.062008ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.245517Z","caller":"traceutil/trace.go:171","msg":"trace[307800251] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"102.355031ms","start":"2026-05-29T17:15:54.14307Z","end":"2026-05-29T17:15:54.245425Z","steps":["trace[307800251] 'process raft request' (duration: 102.087664ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:15:54.246015Z","caller":"traceutil/trace.go:171","msg":"trace[1564046514] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"106.790652ms","start":"2026-05-29T17:15:54.139171Z","end":"2026-05-29T17:15:54.245961Z","steps":["trace[1564046514] 'process raft request' (duration: 96.326109ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:16:00.634049Z","caller":"traceutil/trace.go:171","msg":"trace[676868823] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"165.409704ms","start":"2026-05-29T17:16:00.46857Z","end":"2026-05-29T17:16:00.63398Z","steps":["trace[676868823] 'process raft request' (duration: 164.598292ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:16:01.323558Z","caller":"traceutil/trace.go:171","msg":"trace[1605755592] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"105.131616ms","start":"2026-05-29T17:16:01.218401Z","end":"2026-05-29T17:16:01.323533Z","steps":["trace[1605755592] 'process raft request' (duration: 94.085216ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:16:01.626831Z","caller":"traceutil/trace.go:171","msg":"trace[2078659253] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"173.844189ms","start":"2026-05-29T17:16:01.452967Z","end":"2026-05-29T17:16:01.626811Z","steps":["trace[2078659253] 'process raft request' (duration: 173.295755ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:16:02.290461Z","caller":"traceutil/trace.go:171","msg":"trace[1522233200] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"463.513967ms","start":"2026-05-29T17:16:01.82692Z","end":"2026-05-29T17:16:02.290434Z","steps":["trace[1522233200] 'read index received' (duration: 462.464671ms)","trace[1522233200] 'applied index is now lower than readState.Index' (duration: 1.047576ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:16:02.290508Z","caller":"traceutil/trace.go:171","msg":"trace[936290990] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"541.435124ms","start":"2026-05-29T17:16:01.749036Z","end":"2026-05-29T17:16:02.290471Z","steps":["trace[936290990] 'process raft request' (duration: 540.665665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:16:02.29073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:16:01.748999Z","time spent":"541.631538ms","remote":"127.0.0.1:60162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T17:16:02.290771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.830985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-25xts\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-29T17:16:02.290826Z","caller":"traceutil/trace.go:171","msg":"trace[1474123464] range","detail":"{range_begin:/registry/pods/kube-system/cilium-25xts; range_end:; response_count:1; response_revision:651; }","duration":"463.945348ms","start":"2026-05-29T17:16:01.826865Z","end":"2026-05-29T17:16:02.290811Z","steps":["trace[1474123464] 'agreement among raft nodes before linearized reading' (duration: 463.714492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:16:02.29087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:16:01.826834Z","time spent":"464.02383ms","remote":"127.0.0.1:60262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-25xts\" "} {"level":"info","ts":"2026-05-29T17:16:27.194932Z","caller":"traceutil/trace.go:171","msg":"trace[1012173904] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"147.10872ms","start":"2026-05-29T17:16:27.04779Z","end":"2026-05-29T17:16:27.194899Z","steps":["trace[1012173904] 'process raft request' (duration: 146.228843ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:16:27.195016Z","caller":"traceutil/trace.go:171","msg":"trace[1504516868] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"142.084528ms","start":"2026-05-29T17:16:27.05291Z","end":"2026-05-29T17:16:27.194995Z","steps":["trace[1504516868] 'process raft request' (duration: 141.909335ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:16:32.072535Z","caller":"traceutil/trace.go:171","msg":"trace[726447119] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"102.245924ms","start":"2026-05-29T17:16:31.970268Z","end":"2026-05-29T17:16:32.072514Z","steps":["trace[726447119] 'process raft request' (duration: 102.195283ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:16:32.072624Z","caller":"traceutil/trace.go:171","msg":"trace[913242508] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"102.519389ms","start":"2026-05-29T17:16:31.970081Z","end":"2026-05-29T17:16:32.0726Z","steps":["trace[913242508] 'process raft request' (duration: 45.713083ms)","trace[913242508] 'compare' (duration: 56.529641ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:16:50.216436Z","caller":"traceutil/trace.go:171","msg":"trace[182087823] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"151.806614ms","start":"2026-05-29T17:16:50.064601Z","end":"2026-05-29T17:16:50.216407Z","steps":["trace[182087823] 'process raft request' (duration: 145.78771ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:16:50.217078Z","caller":"traceutil/trace.go:171","msg":"trace[365806963] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"135.348797ms","start":"2026-05-29T17:16:50.081708Z","end":"2026-05-29T17:16:50.217057Z","steps":["trace[365806963] 'read index received' (duration: 128.721232ms)","trace[365806963] 'applied index is now lower than readState.Index' (duration: 6.625595ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:16:50.217389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.653823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T17:16:50.217535Z","caller":"traceutil/trace.go:171","msg":"trace[1058727053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1220; }","duration":"135.842096ms","start":"2026-05-29T17:16:50.08167Z","end":"2026-05-29T17:16:50.217512Z","steps":["trace[1058727053] 'agreement among raft nodes before linearized reading' (duration: 135.572232ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:16:50.658583Z","caller":"traceutil/trace.go:171","msg":"trace[551405999] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"434.908037ms","start":"2026-05-29T17:16:50.2236Z","end":"2026-05-29T17:16:50.658508Z","steps":["trace[551405999] 'process raft request' (duration: 434.315127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:16:50.658866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:16:50.22359Z","time spent":"435.172372ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T17:16:50.658962Z","caller":"traceutil/trace.go:171","msg":"trace[1532819060] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"435.69193ms","start":"2026-05-29T17:16:50.223242Z","end":"2026-05-29T17:16:50.658934Z","steps":["trace[1532819060] 'process raft request' (duration: 389.260811ms)","trace[1532819060] 'compare' (duration: 44.980455ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:16:50.659084Z","caller":"traceutil/trace.go:171","msg":"trace[1713213343] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"173.9243ms","start":"2026-05-29T17:16:50.485127Z","end":"2026-05-29T17:16:50.659051Z","steps":["trace[1713213343] 'process raft request' (duration: 172.890063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:16:50.659133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:16:50.223217Z","time spent":"435.827903ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T17:16:54.420463Z","caller":"traceutil/trace.go:171","msg":"trace[1633589426] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"106.822256ms","start":"2026-05-29T17:16:54.313614Z","end":"2026-05-29T17:16:54.420437Z","steps":["trace[1633589426] 'process raft request' (duration: 106.553842ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:17:15.681757Z","caller":"traceutil/trace.go:171","msg":"trace[36864408] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"112.971167ms","start":"2026-05-29T17:17:15.568759Z","end":"2026-05-29T17:17:15.68173Z","steps":["trace[36864408] 'process raft request' (duration: 112.45696ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:17:41.451815Z","caller":"traceutil/trace.go:171","msg":"trace[138243635] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"100.30785ms","start":"2026-05-29T17:17:41.351484Z","end":"2026-05-29T17:17:41.451792Z","steps":["trace[138243635] 'process raft request' (duration: 100.157778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:17:41.786018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.6802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T17:17:41.786122Z","caller":"traceutil/trace.go:171","msg":"trace[1594235176] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"328.292262ms","start":"2026-05-29T17:17:41.457813Z","end":"2026-05-29T17:17:41.786105Z","steps":["trace[1594235176] 'process raft request' (duration: 223.326349ms)","trace[1594235176] 'compare' (duration: 104.549898ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:17:41.786194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:17:41.45779Z","time spent":"328.378493ms","remote":"127.0.0.1:60366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T17:17:41.858319Z","caller":"traceutil/trace.go:171","msg":"trace[709896312] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"400.211574ms","start":"2026-05-29T17:17:41.458073Z","end":"2026-05-29T17:17:41.858285Z","steps":["trace[709896312] 'process raft request' (duration: 399.943211ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:17:41.858505Z","caller":"traceutil/trace.go:171","msg":"trace[1153191432] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1975; }","duration":"340.921836ms","start":"2026-05-29T17:17:41.517559Z","end":"2026-05-29T17:17:41.858481Z","steps":["trace[1153191432] 'read index received' (duration: 163.629744ms)","trace[1153191432] 'applied index is now lower than readState.Index' (duration: 177.290902ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:17:41.858761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:17:41.458057Z","time spent":"400.350957ms","remote":"127.0.0.1:60366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T17:17:41.858864Z","caller":"traceutil/trace.go:171","msg":"trace[439221793] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"193.015026ms","start":"2026-05-29T17:17:41.665835Z","end":"2026-05-29T17:17:41.85885Z","steps":["trace[439221793] 'process raft request' (duration: 192.6006ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:17:41.858908Z","caller":"traceutil/trace.go:171","msg":"trace[1183716972] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"400.62962ms","start":"2026-05-29T17:17:41.458266Z","end":"2026-05-29T17:17:41.858895Z","steps":["trace[1183716972] 'process raft request' (duration: 400.036103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:17:41.858962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:17:41.458239Z","time spent":"400.695011ms","remote":"127.0.0.1:60246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T17:17:41.859072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.511904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-29T17:17:41.859105Z","caller":"traceutil/trace.go:171","msg":"trace[1243095655] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1905; }","duration":"341.602614ms","start":"2026-05-29T17:17:41.517491Z","end":"2026-05-29T17:17:41.859094Z","steps":["trace[1243095655] 'agreement among raft nodes before linearized reading' (duration: 341.479772ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:17:41.85913Z","caller":"traceutil/trace.go:171","msg":"trace[1192417478] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"401.020065ms","start":"2026-05-29T17:17:41.4581Z","end":"2026-05-29T17:17:41.85912Z","steps":["trace[1192417478] 'process raft request' (duration: 400.098603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:17:41.859134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:17:41.517467Z","time spent":"341.659866ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-29T17:17:41.859184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:17:41.458092Z","time spent":"401.063396ms","remote":"127.0.0.1:60246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T17:17:41.859174Z","caller":"traceutil/trace.go:171","msg":"trace[38024635] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"399.276402ms","start":"2026-05-29T17:17:41.459876Z","end":"2026-05-29T17:17:41.859152Z","steps":["trace[38024635] 'process raft request' (duration: 398.513752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:17:41.859296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:17:41.459864Z","time spent":"399.374844ms","remote":"127.0.0.1:60262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T17:17:41.859313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.951559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T17:17:41.859346Z","caller":"traceutil/trace.go:171","msg":"trace[1277321137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1905; }","duration":"227.01284ms","start":"2026-05-29T17:17:41.632322Z","end":"2026-05-29T17:17:41.859335Z","steps":["trace[1277321137] 'agreement among raft nodes before linearized reading' (duration: 226.9606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:17:41.993511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.363992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T17:17:41.993715Z","caller":"traceutil/trace.go:171","msg":"trace[1196096900] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"124.381058ms","start":"2026-05-29T17:17:41.869313Z","end":"2026-05-29T17:17:41.993694Z","steps":["trace[1196096900] 'process raft request' (duration: 124.308497ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:17:41.993757Z","caller":"traceutil/trace.go:171","msg":"trace[700904360] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"128.91211ms","start":"2026-05-29T17:17:41.864807Z","end":"2026-05-29T17:17:41.993719Z","steps":["trace[700904360] 'process raft request' (duration: 22.233264ms)","trace[700904360] 'compare' (duration: 106.18967ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:17:54.268203Z","caller":"traceutil/trace.go:171","msg":"trace[1641095234] linearizableReadLoop","detail":"{readStateIndex:2088; appliedIndex:2087; }","duration":"185.896195ms","start":"2026-05-29T17:17:54.08229Z","end":"2026-05-29T17:17:54.268186Z","steps":["trace[1641095234] 'read index received' (duration: 185.749663ms)","trace[1641095234] 'applied index is now lower than readState.Index' (duration: 145.891µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:17:54.268379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.083947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-29T17:17:54.268417Z","caller":"traceutil/trace.go:171","msg":"trace[1478670411] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2009; }","duration":"186.171337ms","start":"2026-05-29T17:17:54.082233Z","end":"2026-05-29T17:17:54.268404Z","steps":["trace[1478670411] 'agreement among raft nodes before linearized reading' (duration: 186.025895ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:17:54.268373Z","caller":"traceutil/trace.go:171","msg":"trace[644597764] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"221.55468ms","start":"2026-05-29T17:17:54.046796Z","end":"2026-05-29T17:17:54.26835Z","steps":["trace[644597764] 'process raft request' (duration: 221.289606ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:19:43.785699Z","caller":"traceutil/trace.go:171","msg":"trace[1938085913] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"133.127242ms","start":"2026-05-29T17:19:43.652544Z","end":"2026-05-29T17:19:43.785671Z","steps":["trace[1938085913] 'process raft request' (duration: 66.962883ms)","trace[1938085913] 'compare' (duration: 66.052056ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:19:43.844187Z","caller":"traceutil/trace.go:171","msg":"trace[16126225] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"191.579942ms","start":"2026-05-29T17:19:43.652587Z","end":"2026-05-29T17:19:43.844167Z","steps":["trace[16126225] 'process raft request' (duration: 191.439458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:19:44.176883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.152273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-29T17:19:44.176914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.243672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T17:19:44.176989Z","caller":"traceutil/trace.go:171","msg":"trace[47524852] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2727; }","duration":"116.367147ms","start":"2026-05-29T17:19:44.060605Z","end":"2026-05-29T17:19:44.176973Z","steps":["trace[47524852] 'count revisions from in-memory index tree' (duration: 116.17564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:19:44.176993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.371872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T17:19:44.177094Z","caller":"traceutil/trace.go:171","msg":"trace[1261242275] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2727; }","duration":"109.514127ms","start":"2026-05-29T17:19:44.06755Z","end":"2026-05-29T17:19:44.177064Z","steps":["trace[1261242275] 'count revisions from in-memory index tree' (duration: 109.2963ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:19:44.176984Z","caller":"traceutil/trace.go:171","msg":"trace[957888872] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2727; }","duration":"132.308469ms","start":"2026-05-29T17:19:44.044654Z","end":"2026-05-29T17:19:44.176962Z","steps":["trace[957888872] 'range keys from in-memory index tree' (duration: 131.951037ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:22:04.758875Z","caller":"traceutil/trace.go:171","msg":"trace[1146095838] linearizableReadLoop","detail":"{readStateIndex:3690; appliedIndex:3689; }","duration":"124.147226ms","start":"2026-05-29T17:22:04.634704Z","end":"2026-05-29T17:22:04.758851Z","steps":["trace[1146095838] 'read index received' (duration: 123.902861ms)","trace[1146095838] 'applied index is now lower than readState.Index' (duration: 243.205µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:22:04.75898Z","caller":"traceutil/trace.go:171","msg":"trace[939873610] transaction","detail":"{read_only:false; response_revision:3553; number_of_response:1; }","duration":"130.557231ms","start":"2026-05-29T17:22:04.628396Z","end":"2026-05-29T17:22:04.758953Z","steps":["trace[939873610] 'process raft request' (duration: 130.296606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:22:04.759067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.349599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T17:22:04.759159Z","caller":"traceutil/trace.go:171","msg":"trace[1263607851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3553; }","duration":"124.492902ms","start":"2026-05-29T17:22:04.634636Z","end":"2026-05-29T17:22:04.759129Z","steps":["trace[1263607851] 'agreement among raft nodes before linearized reading' (duration: 124.36961ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:22:39.638128Z","caller":"traceutil/trace.go:171","msg":"trace[365673511] linearizableReadLoop","detail":"{readStateIndex:3889; appliedIndex:3887; }","duration":"114.42618ms","start":"2026-05-29T17:22:39.523682Z","end":"2026-05-29T17:22:39.638108Z","steps":["trace[365673511] 'read index received' (duration: 22.717733ms)","trace[365673511] 'applied index is now lower than readState.Index' (duration: 91.707197ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:22:39.638294Z","caller":"traceutil/trace.go:171","msg":"trace[1161963870] transaction","detail":"{read_only:false; response_revision:3745; number_of_response:1; }","duration":"124.281171ms","start":"2026-05-29T17:22:39.514004Z","end":"2026-05-29T17:22:39.638285Z","steps":["trace[1161963870] 'process raft request' (duration: 102.593606ms)","trace[1161963870] 'compare' (duration: 21.384358ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:22:39.638483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.765017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T17:22:39.638599Z","caller":"traceutil/trace.go:171","msg":"trace[878243709] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:3745; }","duration":"114.91604ms","start":"2026-05-29T17:22:39.523656Z","end":"2026-05-29T17:22:39.638572Z","steps":["trace[878243709] 'agreement among raft nodes before linearized reading' (duration: 114.698146ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:23:40.422439Z","caller":"traceutil/trace.go:171","msg":"trace[95141549] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"122.633769ms","start":"2026-05-29T17:23:40.299768Z","end":"2026-05-29T17:23:40.422401Z","steps":["trace[95141549] 'process raft request' (duration: 113.337435ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:23:46.631018Z","caller":"traceutil/trace.go:171","msg":"trace[550835610] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"164.004934ms","start":"2026-05-29T17:23:46.466991Z","end":"2026-05-29T17:23:46.630996Z","steps":["trace[550835610] 'process raft request' (duration: 163.879842ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:23:46.721249Z","caller":"traceutil/trace.go:171","msg":"trace[1337759394] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"131.741463ms","start":"2026-05-29T17:23:46.589482Z","end":"2026-05-29T17:23:46.721223Z","steps":["trace[1337759394] 'process raft request' (duration: 91.014985ms)","trace[1337759394] 'compare' (duration: 40.581586ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:23:54.385048Z","caller":"traceutil/trace.go:171","msg":"trace[2030432031] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"334.140416ms","start":"2026-05-29T17:23:54.050877Z","end":"2026-05-29T17:23:54.385018Z","steps":["trace[2030432031] 'process raft request' (duration: 333.866582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:23:54.385193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:23:54.050858Z","time spent":"334.267758ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T17:23:54.385327Z","caller":"traceutil/trace.go:171","msg":"trace[881932530] linearizableReadLoop","detail":"{readStateIndex:5078; appliedIndex:5077; }","duration":"316.284996ms","start":"2026-05-29T17:23:54.068975Z","end":"2026-05-29T17:23:54.38526Z","steps":["trace[881932530] 'read index received' (duration: 315.841769ms)","trace[881932530] 'applied index is now lower than readState.Index' (duration: 434.087µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:23:54.385628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.629101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a1146db0-e9ff-4642-88d8-5803941b5496\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-29T17:23:54.385673Z","caller":"traceutil/trace.go:171","msg":"trace[2143100157] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a1146db0-e9ff-4642-88d8-5803941b5496; range_end:; response_count:1; response_revision:4891; }","duration":"316.719003ms","start":"2026-05-29T17:23:54.068941Z","end":"2026-05-29T17:23:54.38566Z","steps":["trace[2143100157] 'agreement among raft nodes before linearized reading' (duration: 316.5347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:23:54.385735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T17:23:54.068926Z","time spent":"316.801144ms","remote":"127.0.0.1:60262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a1146db0-e9ff-4642-88d8-5803941b5496\" "} {"level":"warn","ts":"2026-05-29T17:23:54.385765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.574031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a1146db0-e9ff-4642-88d8-5803941b5496\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-29T17:23:54.385818Z","caller":"traceutil/trace.go:171","msg":"trace[1929979208] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a1146db0-e9ff-4642-88d8-5803941b5496; range_end:; response_count:1; response_revision:4891; }","duration":"249.683162ms","start":"2026-05-29T17:23:54.136116Z","end":"2026-05-29T17:23:54.3858Z","steps":["trace[1929979208] 'agreement among raft nodes before linearized reading' (duration: 249.586711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:23:54.38566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.284895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-29T17:23:54.385945Z","caller":"traceutil/trace.go:171","msg":"trace[627909627] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4891; }","duration":"292.615811ms","start":"2026-05-29T17:23:54.093319Z","end":"2026-05-29T17:23:54.385935Z","steps":["trace[627909627] 'agreement among raft nodes before linearized reading' (duration: 292.197964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T17:23:54.386019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.632901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T17:23:54.386173Z","caller":"traceutil/trace.go:171","msg":"trace[1372051110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4891; }","duration":"209.788183ms","start":"2026-05-29T17:23:54.176299Z","end":"2026-05-29T17:23:54.386087Z","steps":["trace[1372051110] 'agreement among raft nodes before linearized reading' (duration: 209.282415ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:03.929808Z","caller":"traceutil/trace.go:171","msg":"trace[1611719204] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"103.357494ms","start":"2026-05-29T17:24:03.826422Z","end":"2026-05-29T17:24:03.929779Z","steps":["trace[1611719204] 'process raft request' (duration: 103.196512ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:44.809906Z","caller":"traceutil/trace.go:171","msg":"trace[549057423] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"123.437683ms","start":"2026-05-29T17:24:44.686436Z","end":"2026-05-29T17:24:44.809874Z","steps":["trace[549057423] 'process raft request' (duration: 62.492209ms)","trace[549057423] 'compare' (duration: 59.733086ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T17:24:53.821707Z","caller":"traceutil/trace.go:171","msg":"trace[239313023] linearizableReadLoop","detail":"{readStateIndex:5618; appliedIndex:5617; }","duration":"131.863798ms","start":"2026-05-29T17:24:53.689816Z","end":"2026-05-29T17:24:53.82168Z","steps":["trace[239313023] 'read index received' (duration: 131.724686ms)","trace[239313023] 'applied index is now lower than readState.Index' (duration: 137.892µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T17:24:53.821861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.021661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T17:24:53.821836Z","caller":"traceutil/trace.go:171","msg":"trace[2106740330] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"165.595328ms","start":"2026-05-29T17:24:53.656196Z","end":"2026-05-29T17:24:53.821791Z","steps":["trace[2106740330] 'process raft request' (duration: 165.321624ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:24:53.82191Z","caller":"traceutil/trace.go:171","msg":"trace[325116229] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5416; }","duration":"132.142532ms","start":"2026-05-29T17:24:53.689756Z","end":"2026-05-29T17:24:53.821898Z","steps":["trace[325116229] 'agreement among raft nodes before linearized reading' (duration: 132.021271ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T17:25:13.483939Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2882} {"level":"info","ts":"2026-05-29T17:25:13.568305Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2882,"took":"83.093901ms","hash":426523618,"current-db-size-bytes":52793344,"current-db-size":"53 MB","current-db-size-in-use-bytes":29286400,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-29T17:25:13.568373Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":426523618,"revision":2882,"compact-revision":-1} {"level":"info","ts":"2026-05-29T17:30:13.489332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5576} {"level":"info","ts":"2026-05-29T17:30:13.561478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5576,"took":"70.941564ms","hash":2841677209,"current-db-size-bytes":52793344,"current-db-size":"53 MB","current-db-size-in-use-bytes":18468864,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-29T17:30:13.561529Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2841677209,"revision":5576,"compact-revision":2882}