{"level":"warn","ts":"2026-03-31T22:21:01.312043Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T22:21:01.312178Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.51:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.51:2380","--initial-cluster=instance=https://199.19.213.51:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.51:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.51: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-03-31T22:21:01.312256Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T22:21:01.312272Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.51:2380"]} {"level":"info","ts":"2026-03-31T22:21:01.312296Z","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-03-31T22:21:01.313152Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.51:2379"]} {"level":"info","ts":"2026-03-31T22:21:01.313228Z","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.19.213.51:2380"],"listen-peer-urls":["https://199.19.213.51:2380"],"advertise-client-urls":["https://199.19.213.51:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.51:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.51: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-03-31T22:21:01.31695Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.499754ms"} {"level":"info","ts":"2026-03-31T22:21:01.326705Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d8a2e6f117593bd6","cluster-id":"28b0db2e76181428"} {"level":"info","ts":"2026-03-31T22:21:01.326781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T22:21:01.326803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became follower at term 0"} {"level":"info","ts":"2026-03-31T22:21:01.326816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d8a2e6f117593bd6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T22:21:01.326833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became follower at term 1"} {"level":"info","ts":"2026-03-31T22:21:01.326877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 switched to configuration voters=(15610293181572791254)"} {"level":"warn","ts":"2026-03-31T22:21:01.331568Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T22:21:01.332754Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T22:21:01.333841Z","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-03-31T22:21:01.335719Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d8a2e6f117593bd6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T22:21:01.335993Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d8a2e6f117593bd6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T22:21:01.336289Z","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-03-31T22:21:01.336464Z","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-03-31T22:21:01.336487Z","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-03-31T22:21:01.338864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 switched to configuration voters=(15610293181572791254)"} {"level":"info","ts":"2026-03-31T22:21:01.339835Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"28b0db2e76181428","local-member-id":"d8a2e6f117593bd6","added-peer-id":"d8a2e6f117593bd6","added-peer-peer-urls":["https://199.19.213.51:2380"]} {"level":"info","ts":"2026-03-31T22:21:01.340788Z","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-03-31T22:21:01.341057Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.51:2380"} {"level":"info","ts":"2026-03-31T22:21:01.34109Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.51:2380"} {"level":"info","ts":"2026-03-31T22:21:01.341297Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d8a2e6f117593bd6","initial-advertise-peer-urls":["https://199.19.213.51:2380"],"listen-peer-urls":["https://199.19.213.51:2380"],"advertise-client-urls":["https://199.19.213.51:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.51:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T22:21:01.34133Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T22:21:02.129327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T22:21:02.129388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T22:21:02.129431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 received MsgPreVoteResp from d8a2e6f117593bd6 at term 1"} {"level":"info","ts":"2026-03-31T22:21:02.12945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became candidate at term 2"} {"level":"info","ts":"2026-03-31T22:21:02.129462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 received MsgVoteResp from d8a2e6f117593bd6 at term 2"} {"level":"info","ts":"2026-03-31T22:21:02.129477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d8a2e6f117593bd6 became leader at term 2"} {"level":"info","ts":"2026-03-31T22:21:02.129492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d8a2e6f117593bd6 elected leader d8a2e6f117593bd6 at term 2"} {"level":"info","ts":"2026-03-31T22:21:02.130736Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T22:21:02.13173Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T22:21:02.131724Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d8a2e6f117593bd6","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.51:2379]}","request-path":"/0/members/d8a2e6f117593bd6/attributes","cluster-id":"28b0db2e76181428","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T22:21:02.131815Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T22:21:02.132091Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T22:21:02.132289Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T22:21:02.132431Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"28b0db2e76181428","local-member-id":"d8a2e6f117593bd6","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T22:21:02.132609Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T22:21:02.132667Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T22:21:02.13417Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T22:21:02.13533Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.51:2379"} {"level":"info","ts":"2026-03-31T22:21:31.844494Z","caller":"traceutil/trace.go:171","msg":"trace[714653092] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"249.097385ms","start":"2026-03-31T22:21:31.595367Z","end":"2026-03-31T22:21:31.844464Z","steps":["trace[714653092] 'process raft request' (duration: 231.06714ms)","trace[714653092] 'compare' (duration: 17.859972ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:21:31.844855Z","caller":"traceutil/trace.go:171","msg":"trace[1179849541] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:545; }","duration":"138.069458ms","start":"2026-03-31T22:21:31.706757Z","end":"2026-03-31T22:21:31.844826Z","steps":["trace[1179849541] 'read index received' (duration: 119.698447ms)","trace[1179849541] 'applied index is now lower than readState.Index' (duration: 18.370461ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:21:31.844883Z","caller":"traceutil/trace.go:171","msg":"trace[1056402327] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"222.798465ms","start":"2026-03-31T22:21:31.622069Z","end":"2026-03-31T22:21:31.844868Z","steps":["trace[1056402327] 'process raft request' (duration: 222.49744ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:21:31.844973Z","caller":"traceutil/trace.go:171","msg":"trace[1550727103] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"180.117851ms","start":"2026-03-31T22:21:31.664803Z","end":"2026-03-31T22:21:31.844921Z","steps":["trace[1550727103] 'process raft request' (duration: 179.946038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:31.84503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.255712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:21:31.845085Z","caller":"traceutil/trace.go:171","msg":"trace[584032194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"138.336933ms","start":"2026-03-31T22:21:31.70674Z","end":"2026-03-31T22:21:31.845077Z","steps":["trace[584032194] 'agreement among raft nodes before linearized reading' (duration: 138.202011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:21:37.723108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.732998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T22:21:37.723685Z","caller":"traceutil/trace.go:171","msg":"trace[640003787] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"369.898865ms","start":"2026-03-31T22:21:37.353342Z","end":"2026-03-31T22:21:37.723241Z","steps":["trace[640003787] 'process raft request' (duration: 215.462944ms)","trace[640003787] 'compare' (duration: 153.586994ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:21:37.724013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:21:37.353311Z","time spent":"370.477736ms","remote":"127.0.0.1:59734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T22:22:40.304469Z","caller":"traceutil/trace.go:171","msg":"trace[763399878] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"104.280372ms","start":"2026-03-31T22:22:40.200153Z","end":"2026-03-31T22:22:40.304433Z","steps":["trace[763399878] 'process raft request' (duration: 104.049798ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:23:04.679513Z","caller":"traceutil/trace.go:171","msg":"trace[595273339] linearizableReadLoop","detail":"{readStateIndex:1856; appliedIndex:1855; }","duration":"266.855701ms","start":"2026-03-31T22:23:04.412632Z","end":"2026-03-31T22:23:04.679488Z","steps":["trace[595273339] 'read index received' (duration: 266.628746ms)","trace[595273339] 'applied index is now lower than readState.Index' (duration: 225.984µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:23:04.679574Z","caller":"traceutil/trace.go:171","msg":"trace[1495494731] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"271.124871ms","start":"2026-03-31T22:23:04.408427Z","end":"2026-03-31T22:23:04.679552Z","steps":["trace[1495494731] 'process raft request' (duration: 270.888285ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:23:04.67977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.969593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T22:23:04.679786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.137137ms","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-03-31T22:23:04.679839Z","caller":"traceutil/trace.go:171","msg":"trace[1199553927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1788; }","duration":"267.203048ms","start":"2026-03-31T22:23:04.41262Z","end":"2026-03-31T22:23:04.679823Z","steps":["trace[1199553927] 'agreement among raft nodes before linearized reading' (duration: 267.042955ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:23:04.679842Z","caller":"traceutil/trace.go:171","msg":"trace[1077645100] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1788; }","duration":"246.105076ms","start":"2026-03-31T22:23:04.433722Z","end":"2026-03-31T22:23:04.679827Z","steps":["trace[1077645100] 'agreement among raft nodes before linearized reading' (duration: 245.964263ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:23:04.973126Z","caller":"traceutil/trace.go:171","msg":"trace[1327123348] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"132.212108ms","start":"2026-03-31T22:23:04.840884Z","end":"2026-03-31T22:23:04.973097Z","steps":["trace[1327123348] 'process raft request' (duration: 132.052635ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:23:17.552245Z","caller":"traceutil/trace.go:171","msg":"trace[1475543336] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1965; }","duration":"179.008528ms","start":"2026-03-31T22:23:17.373201Z","end":"2026-03-31T22:23:17.55221Z","steps":["trace[1475543336] 'read index received' (duration: 178.808344ms)","trace[1475543336] 'applied index is now lower than readState.Index' (duration: 198.634µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:23:17.552309Z","caller":"traceutil/trace.go:171","msg":"trace[1146608347] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"185.895836ms","start":"2026-03-31T22:23:17.366377Z","end":"2026-03-31T22:23:17.552273Z","steps":["trace[1146608347] 'process raft request' (duration: 185.639842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:23:17.552519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.283523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T22:23:17.552846Z","caller":"traceutil/trace.go:171","msg":"trace[1514800401] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1893; }","duration":"179.67181ms","start":"2026-03-31T22:23:17.37316Z","end":"2026-03-31T22:23:17.552832Z","steps":["trace[1514800401] 'agreement among raft nodes before linearized reading' (duration: 179.191092ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:23:48.17634Z","caller":"traceutil/trace.go:171","msg":"trace[214191859] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"102.72825ms","start":"2026-03-31T22:23:48.073572Z","end":"2026-03-31T22:23:48.1763Z","steps":["trace[214191859] 'process raft request' (duration: 102.485775ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:23:53.823441Z","caller":"traceutil/trace.go:171","msg":"trace[1284043299] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"106.166015ms","start":"2026-03-31T22:23:53.717227Z","end":"2026-03-31T22:23:53.823393Z","steps":["trace[1284043299] 'process raft request' (duration: 82.933573ms)","trace[1284043299] 'compare' (duration: 22.938166ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:23:54.356461Z","caller":"traceutil/trace.go:171","msg":"trace[602719998] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"158.184402ms","start":"2026-03-31T22:23:54.198241Z","end":"2026-03-31T22:23:54.356426Z","steps":["trace[602719998] 'process raft request' (duration: 157.918807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:24:46.863141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.050218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:24:46.863232Z","caller":"traceutil/trace.go:171","msg":"trace[689823307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2561; }","duration":"159.189311ms","start":"2026-03-31T22:24:46.704024Z","end":"2026-03-31T22:24:46.863213Z","steps":["trace[689823307] 'range keys from in-memory index tree' (duration: 158.941006ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:25:20.28068Z","caller":"traceutil/trace.go:171","msg":"trace[1063728467] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"121.928051ms","start":"2026-03-31T22:25:20.158719Z","end":"2026-03-31T22:25:20.280647Z","steps":["trace[1063728467] 'process raft request' (duration: 32.478366ms)","trace[1063728467] 'compare' (duration: 89.020487ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:25:20.835779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.872797ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-31T22:25:20.839618Z","caller":"traceutil/trace.go:171","msg":"trace[340775080] linearizableReadLoop","detail":"{readStateIndex:2870; appliedIndex:2869; }","duration":"133.508205ms","start":"2026-03-31T22:25:20.706087Z","end":"2026-03-31T22:25:20.839595Z","steps":["trace[340775080] 'read index received' (duration: 133.301261ms)","trace[340775080] 'applied index is now lower than readState.Index' (duration: 206.074µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:25:20.839675Z","caller":"traceutil/trace.go:171","msg":"trace[516842605] transaction","detail":"{read_only:false; response_revision:2765; number_of_response:1; }","duration":"151.506741ms","start":"2026-03-31T22:25:20.688138Z","end":"2026-03-31T22:25:20.839645Z","steps":["trace[516842605] 'process raft request' (duration: 151.301587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:25:20.839793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.663989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:25:20.839838Z","caller":"traceutil/trace.go:171","msg":"trace[1980567912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2765; }","duration":"133.74482ms","start":"2026-03-31T22:25:20.706081Z","end":"2026-03-31T22:25:20.839826Z","steps":["trace[1980567912] 'agreement among raft nodes before linearized reading' (duration: 133.642039ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:27:41.741042Z","caller":"traceutil/trace.go:171","msg":"trace[1265832726] linearizableReadLoop","detail":"{readStateIndex:3847; appliedIndex:3846; }","duration":"126.021281ms","start":"2026-03-31T22:27:41.614994Z","end":"2026-03-31T22:27:41.741016Z","steps":["trace[1265832726] 'read index received' (duration: 125.829767ms)","trace[1265832726] 'applied index is now lower than readState.Index' (duration: 190.344µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:27:41.741332Z","caller":"traceutil/trace.go:171","msg":"trace[975492358] transaction","detail":"{read_only:false; response_revision:3708; number_of_response:1; }","duration":"226.645796ms","start":"2026-03-31T22:27:41.514673Z","end":"2026-03-31T22:27:41.741319Z","steps":["trace[975492358] 'process raft request' (duration: 226.139247ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:27:41.741906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.819676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2m2ql\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-31T22:27:41.741985Z","caller":"traceutil/trace.go:171","msg":"trace[666770248] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2m2ql; range_end:; response_count:1; response_revision:3708; }","duration":"127.024189ms","start":"2026-03-31T22:27:41.614944Z","end":"2026-03-31T22:27:41.741968Z","steps":["trace[666770248] 'agreement among raft nodes before linearized reading' (duration: 126.737354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:29:03.942251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.22892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:29:03.942329Z","caller":"traceutil/trace.go:171","msg":"trace[1599611220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4864; }","duration":"236.359423ms","start":"2026-03-31T22:29:03.705952Z","end":"2026-03-31T22:29:03.942312Z","steps":["trace[1599611220] 'range keys from in-memory index tree' (duration: 236.147029ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:29:22.927331Z","caller":"traceutil/trace.go:171","msg":"trace[753149173] linearizableReadLoop","detail":"{readStateIndex:5412; appliedIndex:5411; }","duration":"108.96801ms","start":"2026-03-31T22:29:22.818342Z","end":"2026-03-31T22:29:22.92731Z","steps":["trace[753149173] 'read index received' (duration: 108.912949ms)","trace[753149173] 'applied index is now lower than readState.Index' (duration: 53.991µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:29:22.92742Z","caller":"traceutil/trace.go:171","msg":"trace[1310900527] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"115.222802ms","start":"2026-03-31T22:29:22.81217Z","end":"2026-03-31T22:29:22.927393Z","steps":["trace[1310900527] 'process raft request' (duration: 115.014138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:29:22.927501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.147783ms","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-03-31T22:29:22.927528Z","caller":"traceutil/trace.go:171","msg":"trace[1989801232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5224; }","duration":"109.217605ms","start":"2026-03-31T22:29:22.818302Z","end":"2026-03-31T22:29:22.92752Z","steps":["trace[1989801232] 'agreement among raft nodes before linearized reading' (duration: 109.089333ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:29:23.060089Z","caller":"traceutil/trace.go:171","msg":"trace[557079982] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"157.84066ms","start":"2026-03-31T22:29:22.902222Z","end":"2026-03-31T22:29:23.060062Z","steps":["trace[557079982] 'process raft request' (duration: 124.054237ms)","trace[557079982] 'compare' (duration: 33.673771ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:29:23.091167Z","caller":"traceutil/trace.go:171","msg":"trace[1437229926] transaction","detail":"{read_only:false; response_revision:5226; number_of_response:1; }","duration":"160.725217ms","start":"2026-03-31T22:29:22.930417Z","end":"2026-03-31T22:29:23.091143Z","steps":["trace[1437229926] 'process raft request' (duration: 160.522653ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:29:23.091275Z","caller":"traceutil/trace.go:171","msg":"trace[764540737] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"157.319979ms","start":"2026-03-31T22:29:22.933905Z","end":"2026-03-31T22:29:23.091212Z","steps":["trace[764540737] 'process raft request' (duration: 157.173926ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:29:23.392521Z","caller":"traceutil/trace.go:171","msg":"trace[353219131] linearizableReadLoop","detail":"{readStateIndex:5417; appliedIndex:5416; }","duration":"131.979421ms","start":"2026-03-31T22:29:23.260508Z","end":"2026-03-31T22:29:23.392488Z","steps":["trace[353219131] 'read index received' (duration: 131.778527ms)","trace[353219131] 'applied index is now lower than readState.Index' (duration: 200.054µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:29:23.392644Z","caller":"traceutil/trace.go:171","msg":"trace[28052180] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"140.945047ms","start":"2026-03-31T22:29:23.251655Z","end":"2026-03-31T22:29:23.3926Z","steps":["trace[28052180] 'process raft request' (duration: 140.625361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:29:23.392895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.355519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-03-31T22:29:23.392964Z","caller":"traceutil/trace.go:171","msg":"trace[1459151825] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5229; }","duration":"132.474392ms","start":"2026-03-31T22:29:23.260472Z","end":"2026-03-31T22:29:23.392947Z","steps":["trace[1459151825] 'agreement among raft nodes before linearized reading' (duration: 132.138195ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:31:02.155408Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2993} {"level":"info","ts":"2026-03-31T22:31:02.222965Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2993,"took":"65.737888ms","hash":3020105623,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":34422784,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-03-31T22:31:02.22303Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3020105623,"revision":2993,"compact-revision":-1} {"level":"info","ts":"2026-03-31T22:35:32.941091Z","caller":"traceutil/trace.go:171","msg":"trace[2039031767] transaction","detail":"{read_only:false; response_revision:8115; number_of_response:1; }","duration":"102.042019ms","start":"2026-03-31T22:35:32.839007Z","end":"2026-03-31T22:35:32.941049Z","steps":["trace[2039031767] 'process raft request' (duration: 101.880776ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:36:02.195586Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6148} {"level":"warn","ts":"2026-03-31T22:36:02.574028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.40784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T22:36:02.574136Z","caller":"traceutil/trace.go:171","msg":"trace[1000343179] linearizableReadLoop","detail":"{readStateIndex:8618; appliedIndex:8617; }","duration":"158.903782ms","start":"2026-03-31T22:36:02.415216Z","end":"2026-03-31T22:36:02.574119Z","steps":["trace[1000343179] 'read index received' (duration: 44.921µs)","trace[1000343179] 'applied index is now lower than readState.Index' (duration: 158.857781ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:36:02.574191Z","caller":"traceutil/trace.go:171","msg":"trace[1724925259] transaction","detail":"{read_only:false; response_revision:8327; number_of_response:1; }","duration":"216.261695ms","start":"2026-03-31T22:36:02.357862Z","end":"2026-03-31T22:36:02.574124Z","steps":["trace[1724925259] 'process raft request' (duration: 47.534079ms)","trace[1724925259] 'compare' (duration: 20.365411ms)","trace[1724925259] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:537; } (duration: 147.929927ms)"],"step_count":3} {"level":"warn","ts":"2026-03-31T22:36:02.657345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.103378ms","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-03-31T22:36:02.657447Z","caller":"traceutil/trace.go:171","msg":"trace[864586991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8327; }","duration":"242.259591ms","start":"2026-03-31T22:36:02.415165Z","end":"2026-03-31T22:36:02.657425Z","steps":["trace[864586991] 'agreement among raft nodes before linearized reading' (duration: 159.197477ms)","trace[864586991] 'range keys from in-memory index tree' (duration: 82.815599ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:36:02.723387Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6148,"took":"526.031449ms","hash":3402866316,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":23134208,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-31T22:36:02.723465Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3402866316,"revision":6148,"compact-revision":2993} {"level":"warn","ts":"2026-03-31T22:36:02.72372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.39301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T22:36:02.7239Z","caller":"traceutil/trace.go:171","msg":"trace[1006028226] linearizableReadLoop","detail":"{readStateIndex:8619; appliedIndex:8618; }","duration":"149.720689ms","start":"2026-03-31T22:36:02.574162Z","end":"2026-03-31T22:36:02.723882Z","steps":["trace[1006028226] 'read index received' (duration: 43.109766ms)","trace[1006028226] 'applied index is now lower than readState.Index' (duration: 106.609483ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:36:02.724105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.25101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-03-31T22:36:02.724141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.772416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2m2ql\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-31T22:36:02.724191Z","caller":"traceutil/trace.go:171","msg":"trace[13048242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8329; }","duration":"242.367153ms","start":"2026-03-31T22:36:02.481801Z","end":"2026-03-31T22:36:02.724168Z","steps":["trace[13048242] 'agreement among raft nodes before linearized reading' (duration: 242.120198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:36:02.724116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.117507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T22:36:02.72423Z","caller":"traceutil/trace.go:171","msg":"trace[1382710992] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:8329; }","duration":"104.28107ms","start":"2026-03-31T22:36:02.61994Z","end":"2026-03-31T22:36:02.724221Z","steps":["trace[1382710992] 'agreement among raft nodes before linearized reading' (duration: 104.098896ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:36:02.724185Z","caller":"traceutil/trace.go:171","msg":"trace[880653904] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2m2ql; range_end:; response_count:1; response_revision:8329; }","duration":"276.856137ms","start":"2026-03-31T22:36:02.447317Z","end":"2026-03-31T22:36:02.724173Z","steps":["trace[880653904] 'agreement among raft nodes before linearized reading' (duration: 276.692394ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:36:02.724469Z","caller":"traceutil/trace.go:171","msg":"trace[600176433] transaction","detail":"{read_only:false; response_revision:8328; number_of_response:1; }","duration":"299.866109ms","start":"2026-03-31T22:36:02.423968Z","end":"2026-03-31T22:36:02.723834Z","steps":["trace[600176433] 'process raft request' (duration: 193.279685ms)","trace[600176433] 'compare' (duration: 39.972169ms)","trace[600176433] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; req_size:532; } (duration: 66.291869ms)"],"step_count":3} {"level":"warn","ts":"2026-03-31T22:36:02.724682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:36:02.423953Z","time spent":"300.621843ms","remote":"127.0.0.1:59508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:36:12.48855Z","caller":"traceutil/trace.go:171","msg":"trace[1831361875] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"105.180948ms","start":"2026-03-31T22:36:12.383343Z","end":"2026-03-31T22:36:12.488524Z","steps":["trace[1831361875] 'process raft request' (duration: 104.563316ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:37:02.929627Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000241e.wal"} {"level":"info","ts":"2026-03-31T22:38:37.703725Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d8a2e6f117593bd6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-31T22:38:37.709654Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-31T22:38:37.709764Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-31T22:41:02.200687Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8325} {"level":"info","ts":"2026-03-31T22:41:02.275612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8325,"took":"72.19173ms","hash":1796043760,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":24367104,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-31T22:41:02.275702Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1796043760,"revision":8325,"compact-revision":6148} {"level":"info","ts":"2026-03-31T22:46:02.206839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10673} {"level":"info","ts":"2026-03-31T22:46:02.287133Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10673,"took":"78.002988ms","hash":2693667290,"current-db-size-bytes":58834944,"current-db-size":"59 MB","current-db-size-in-use-bytes":20320256,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-03-31T22:46:02.287213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2693667290,"revision":10673,"compact-revision":8325}