{"level":"warn","ts":"2026-05-25T00:38:44.384485Z","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-25T00:38:44.384575Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-25T00:38:44.384636Z","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-25T00:38:44.384651Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-25T00:38:44.384671Z","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-25T00:38:44.385292Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-05-25T00:38:44.385364Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-25T00:38:44.389967Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.415682ms"} {"level":"info","ts":"2026-05-25T00:38:44.402363Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-05-25T00:38:44.402504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T00:38:44.402556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-05-25T00:38:44.402576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T00:38:44.40261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-05-25T00:38:44.402678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-05-25T00:38:44.415344Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T00:38:44.416537Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T00:38:44.418386Z","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-25T00:38:44.421047Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T00:38:44.421651Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T00:38:44.4219Z","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-25T00:38:44.422066Z","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-25T00:38:44.422083Z","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-25T00:38:44.423549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-05-25T00:38:44.424123Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-25T00:38:44.425261Z","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-25T00:38:44.425712Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T00:38:44.425848Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T00:38:44.426085Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-25T00:38:44.426196Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-25T00:38:45.003642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T00:38:45.003686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T00:38:45.003709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-05-25T00:38:45.003721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-05-25T00:38:45.003726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-25T00:38:45.003734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-05-25T00:38:45.003741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-25T00:38:45.004748Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:38:45.005651Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T00:38:45.005709Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T00:38:45.005857Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T00:38:45.009102Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-25T00:38:45.009331Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-05-25T00:38:45.010256Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:38:45.010353Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:38:45.010391Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:38:45.014228Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T00:38:45.014267Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T00:39:19.59224Z","caller":"traceutil/trace.go:171","msg":"trace[463241039] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"102.757913ms","start":"2026-05-25T00:39:19.48946Z","end":"2026-05-25T00:39:19.592217Z","steps":["trace[463241039] 'process raft request' (duration: 102.522992ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:39:25.192665Z","caller":"traceutil/trace.go:171","msg":"trace[870429948] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"263.593045ms","start":"2026-05-25T00:39:24.929049Z","end":"2026-05-25T00:39:25.192642Z","steps":["trace[870429948] 'read index received' (duration: 262.861171ms)","trace[870429948] 'applied index is now lower than readState.Index' (duration: 730.944µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:39:25.193979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.863492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T00:39:25.194141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.999052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:39:25.194197Z","caller":"traceutil/trace.go:171","msg":"trace[1295976671] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:581; }","duration":"265.085794ms","start":"2026-05-25T00:39:24.929103Z","end":"2026-05-25T00:39:25.194189Z","steps":["trace[1295976671] 'agreement among raft nodes before linearized reading' (duration: 264.990683ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:39:25.194203Z","caller":"traceutil/trace.go:171","msg":"trace[1382500701] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:581; }","duration":"265.123444ms","start":"2026-05-25T00:39:24.929015Z","end":"2026-05-25T00:39:25.194138Z","steps":["trace[1382500701] 'agreement among raft nodes before linearized reading' (duration: 264.805832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:39:25.194384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.543158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:39:25.194407Z","caller":"traceutil/trace.go:171","msg":"trace[1490328914] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:581; }","duration":"207.573578ms","start":"2026-05-25T00:39:24.986828Z","end":"2026-05-25T00:39:25.194402Z","steps":["trace[1490328914] 'agreement among raft nodes before linearized reading' (duration: 207.538138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:39:25.194495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.730925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:39:25.194517Z","caller":"traceutil/trace.go:171","msg":"trace[96963751] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:581; }","duration":"208.758506ms","start":"2026-05-25T00:39:24.985753Z","end":"2026-05-25T00:39:25.194511Z","steps":["trace[96963751] 'agreement among raft nodes before linearized reading' (duration: 208.727825ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:39:25.194613Z","caller":"traceutil/trace.go:171","msg":"trace[245898129] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"288.021337ms","start":"2026-05-25T00:39:24.906577Z","end":"2026-05-25T00:39:25.194598Z","steps":["trace[245898129] 'process raft request' (duration: 285.505623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:39:25.194622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.891892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:39:25.194684Z","caller":"traceutil/trace.go:171","msg":"trace[1330835758] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:581; }","duration":"264.978442ms","start":"2026-05-25T00:39:24.929691Z","end":"2026-05-25T00:39:25.194669Z","steps":["trace[1330835758] 'agreement among raft nodes before linearized reading' (duration: 264.896452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:39:25.19477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.021027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:39:25.194792Z","caller":"traceutil/trace.go:171","msg":"trace[1179108013] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:581; }","duration":"209.052007ms","start":"2026-05-25T00:39:24.985734Z","end":"2026-05-25T00:39:25.194786Z","steps":["trace[1179108013] 'agreement among raft nodes before linearized reading' (duration: 209.020027ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:39:26.137264Z","caller":"traceutil/trace.go:171","msg":"trace[265971846] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"339.584663ms","start":"2026-05-25T00:39:25.79764Z","end":"2026-05-25T00:39:26.137225Z","steps":["trace[265971846] 'process raft request' (duration: 339.053381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:39:26.137472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.547376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-krsc4\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-25T00:39:26.137581Z","caller":"traceutil/trace.go:171","msg":"trace[1481264599] range","detail":"{range_begin:/registry/pods/kube-system/cilium-krsc4; range_end:; response_count:1; response_revision:587; }","duration":"329.658336ms","start":"2026-05-25T00:39:25.807871Z","end":"2026-05-25T00:39:26.137529Z","steps":["trace[1481264599] 'agreement among raft nodes before linearized reading' (duration: 329.424985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:39:26.137704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:39:25.807859Z","time spent":"329.835948ms","remote":"127.0.0.1:46428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-krsc4\" "} {"level":"warn","ts":"2026-05-25T00:39:26.138039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:39:25.797613Z","time spent":"339.732095ms","remote":"127.0.0.1:36796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T00:39:26.137008Z","caller":"traceutil/trace.go:171","msg":"trace[1968134466] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"329.080883ms","start":"2026-05-25T00:39:25.807899Z","end":"2026-05-25T00:39:26.13698Z","steps":["trace[1968134466] 'read index received' (duration: 328.698191ms)","trace[1968134466] 'applied index is now lower than readState.Index' (duration: 381.552µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:39:26.138684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.318781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:39:26.138768Z","caller":"traceutil/trace.go:171","msg":"trace[1365566289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"304.425001ms","start":"2026-05-25T00:39:25.834299Z","end":"2026-05-25T00:39:26.138724Z","steps":["trace[1365566289] 'agreement among raft nodes before linearized reading' (duration: 303.914239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:39:26.138818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:39:25.834292Z","time spent":"304.513672ms","remote":"127.0.0.1:46218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-25T00:41:01.556009Z","caller":"traceutil/trace.go:171","msg":"trace[427449991] linearizableReadLoop","detail":"{readStateIndex:1896; appliedIndex:1895; }","duration":"275.979343ms","start":"2026-05-25T00:41:01.280001Z","end":"2026-05-25T00:41:01.55598Z","steps":["trace[427449991] 'read index received' (duration: 236.736199ms)","trace[427449991] 'applied index is now lower than readState.Index' (duration: 39.241584ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:41:01.556325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.249408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4927aca6-8880-474a-b940-19ebaf5c9f5d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-25T00:41:01.556432Z","caller":"traceutil/trace.go:171","msg":"trace[512515913] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4927aca6-8880-474a-b940-19ebaf5c9f5d; range_end:; response_count:1; response_revision:1826; }","duration":"276.460152ms","start":"2026-05-25T00:41:01.279955Z","end":"2026-05-25T00:41:01.556415Z","steps":["trace[512515913] 'agreement among raft nodes before linearized reading' (duration: 276.166466ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:41:01.556575Z","caller":"traceutil/trace.go:171","msg":"trace[1419143196] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"310.312707ms","start":"2026-05-25T00:41:01.245738Z","end":"2026-05-25T00:41:01.556051Z","steps":["trace[1419143196] 'process raft request' (duration: 271.044643ms)","trace[1419143196] 'compare' (duration: 38.757315ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:41:01.556756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.885556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4927aca6-8880-474a-b940-19ebaf5c9f5d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-25T00:41:01.557433Z","caller":"traceutil/trace.go:171","msg":"trace[2140743461] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4927aca6-8880-474a-b940-19ebaf5c9f5d; range_end:; response_count:1; response_revision:1826; }","duration":"217.6426ms","start":"2026-05-25T00:41:01.339747Z","end":"2026-05-25T00:41:01.55739Z","steps":["trace[2140743461] 'agreement among raft nodes before linearized reading' (duration: 216.664263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:41:01.557778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:41:01.245712Z","time spent":"311.509049ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T00:41:01.940531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.155813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:41:01.940676Z","caller":"traceutil/trace.go:171","msg":"trace[2075139480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1832; }","duration":"106.297584ms","start":"2026-05-25T00:41:01.834299Z","end":"2026-05-25T00:41:01.940596Z","steps":["trace[2075139480] 'range keys from in-memory index tree' (duration: 106.003349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:41:01.941746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.60158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-25T00:41:01.941818Z","caller":"traceutil/trace.go:171","msg":"trace[1742733442] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1832; }","duration":"106.700563ms","start":"2026-05-25T00:41:01.835104Z","end":"2026-05-25T00:41:01.941805Z","steps":["trace[1742733442] 'range keys from in-memory index tree' (duration: 105.345636ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:41:36.299418Z","caller":"traceutil/trace.go:171","msg":"trace[1527158378] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"113.059804ms","start":"2026-05-25T00:41:36.186303Z","end":"2026-05-25T00:41:36.299363Z","steps":["trace[1527158378] 'process raft request' (duration: 112.830961ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:41:36.505643Z","caller":"traceutil/trace.go:171","msg":"trace[1754451629] linearizableReadLoop","detail":"{readStateIndex:2148; appliedIndex:2147; }","duration":"122.86164ms","start":"2026-05-25T00:41:36.382755Z","end":"2026-05-25T00:41:36.505617Z","steps":["trace[1754451629] 'read index received' (duration: 38.738679ms)","trace[1754451629] 'applied index is now lower than readState.Index' (duration: 84.121591ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:41:36.505796Z","caller":"traceutil/trace.go:171","msg":"trace[570621356] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"199.895922ms","start":"2026-05-25T00:41:36.305842Z","end":"2026-05-25T00:41:36.505738Z","steps":["trace[570621356] 'process raft request' (duration: 115.651469ms)","trace[570621356] 'compare' (duration: 84.01388ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:41:36.506058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.296406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-25T00:41:36.506153Z","caller":"traceutil/trace.go:171","msg":"trace[642416421] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2070; }","duration":"123.437448ms","start":"2026-05-25T00:41:36.382682Z","end":"2026-05-25T00:41:36.506119Z","steps":["trace[642416421] 'agreement among raft nodes before linearized reading' (duration: 123.035973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:41:37.061152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.051537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T00:41:37.06127Z","caller":"traceutil/trace.go:171","msg":"trace[1969570297] linearizableReadLoop","detail":"{readStateIndex:2151; appliedIndex:2150; }","duration":"268.370281ms","start":"2026-05-25T00:41:36.792879Z","end":"2026-05-25T00:41:37.06125Z","steps":["trace[1969570297] 'read index received' (duration: 124.547474ms)","trace[1969570297] 'applied index is now lower than readState.Index' (duration: 143.820697ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:41:37.061333Z","caller":"traceutil/trace.go:171","msg":"trace[1709776019] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"309.331731ms","start":"2026-05-25T00:41:36.751969Z","end":"2026-05-25T00:41:37.061301Z","steps":["trace[1709776019] 'process raft request' (duration: 165.550015ms)","trace[1709776019] 'compare' (duration: 142.783832ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:41:37.061491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.889717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:41:37.061576Z","caller":"traceutil/trace.go:171","msg":"trace[1713304456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2073; }","duration":"228.010378ms","start":"2026-05-25T00:41:36.83355Z","end":"2026-05-25T00:41:37.061561Z","steps":["trace[1713304456] 'agreement among raft nodes before linearized reading' (duration: 227.907937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:41:37.061578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:41:36.751947Z","time spent":"309.517203ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T00:41:37.061421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.549343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T00:41:37.061828Z","caller":"traceutil/trace.go:171","msg":"trace[967642597] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2073; }","duration":"269.010439ms","start":"2026-05-25T00:41:36.792801Z","end":"2026-05-25T00:41:37.061812Z","steps":["trace[967642597] 'agreement among raft nodes before linearized reading' (duration: 268.552433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:41:37.061992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.919035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-25T00:41:37.062001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.339802ms","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-25T00:41:37.062051Z","caller":"traceutil/trace.go:171","msg":"trace[57359490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2073; }","duration":"179.006735ms","start":"2026-05-25T00:41:36.883029Z","end":"2026-05-25T00:41:37.062036Z","steps":["trace[57359490] 'agreement among raft nodes before linearized reading' (duration: 178.813363ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:41:37.062064Z","caller":"traceutil/trace.go:171","msg":"trace[1536237675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2073; }","duration":"151.422833ms","start":"2026-05-25T00:41:36.910624Z","end":"2026-05-25T00:41:37.062047Z","steps":["trace[1536237675] 'agreement among raft nodes before linearized reading' (duration: 151.2327ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:41:37.229148Z","caller":"traceutil/trace.go:171","msg":"trace[926897324] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"161.494803ms","start":"2026-05-25T00:41:37.067627Z","end":"2026-05-25T00:41:37.229122Z","steps":["trace[926897324] 'process raft request' (duration: 108.000264ms)","trace[926897324] 'compare' (duration: 53.313667ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:41:37.229577Z","caller":"traceutil/trace.go:171","msg":"trace[1052304357] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"160.667642ms","start":"2026-05-25T00:41:37.068864Z","end":"2026-05-25T00:41:37.229531Z","steps":["trace[1052304357] 'process raft request' (duration: 160.210916ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:42:40.986072Z","caller":"traceutil/trace.go:171","msg":"trace[730306061] linearizableReadLoop","detail":"{readStateIndex:2614; appliedIndex:2613; }","duration":"131.368173ms","start":"2026-05-25T00:42:40.85467Z","end":"2026-05-25T00:42:40.986038Z","steps":["trace[730306061] 'read index received' (duration: 131.113209ms)","trace[730306061] 'applied index is now lower than readState.Index' (duration: 253.194µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:42:40.986225Z","caller":"traceutil/trace.go:171","msg":"trace[2120431356] transaction","detail":"{read_only:false; response_revision:2521; number_of_response:1; }","duration":"133.928369ms","start":"2026-05-25T00:42:40.852251Z","end":"2026-05-25T00:42:40.986179Z","steps":["trace[2120431356] 'process raft request' (duration: 133.555225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:42:40.986468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.558335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-25T00:42:40.986515Z","caller":"traceutil/trace.go:171","msg":"trace[166148461] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2521; }","duration":"131.672417ms","start":"2026-05-25T00:42:40.85483Z","end":"2026-05-25T00:42:40.986502Z","steps":["trace[166148461] 'agreement among raft nodes before linearized reading' (duration: 131.446774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:42:40.986462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.729696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-05-25T00:42:40.986604Z","caller":"traceutil/trace.go:171","msg":"trace[557451492] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2521; }","duration":"131.979549ms","start":"2026-05-25T00:42:40.854603Z","end":"2026-05-25T00:42:40.986582Z","steps":["trace[557451492] 'agreement among raft nodes before linearized reading' (duration: 131.594046ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:47:12.551746Z","caller":"traceutil/trace.go:171","msg":"trace[596731413] linearizableReadLoop","detail":"{readStateIndex:4886; appliedIndex:4885; }","duration":"269.854206ms","start":"2026-05-25T00:47:12.281866Z","end":"2026-05-25T00:47:12.55172Z","steps":["trace[596731413] 'read index received' (duration: 249.703926ms)","trace[596731413] 'applied index is now lower than readState.Index' (duration: 20.14852ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:47:12.551825Z","caller":"traceutil/trace.go:171","msg":"trace[1634328437] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"279.111474ms","start":"2026-05-25T00:47:12.272683Z","end":"2026-05-25T00:47:12.551795Z","steps":["trace[1634328437] 'process raft request' (duration: 258.966034ms)","trace[1634328437] 'compare' (duration: 19.787043ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:47:12.552062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.170512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-05-25T00:47:12.552097Z","caller":"traceutil/trace.go:171","msg":"trace[2117730570] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4711; }","duration":"270.275935ms","start":"2026-05-25T00:47:12.281812Z","end":"2026-05-25T00:47:12.552088Z","steps":["trace[2117730570] 'agreement among raft nodes before linearized reading' (duration: 270.04997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:12.552312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.040609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-05-25T00:47:12.552336Z","caller":"traceutil/trace.go:171","msg":"trace[1982072283] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4711; }","duration":"208.1148ms","start":"2026-05-25T00:47:12.344213Z","end":"2026-05-25T00:47:12.552328Z","steps":["trace[1982072283] 'agreement among raft nodes before linearized reading' (duration: 208.035589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:12.552497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.261149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-05-25T00:47:12.552514Z","caller":"traceutil/trace.go:171","msg":"trace[1579718310] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4711; }","duration":"100.300949ms","start":"2026-05-25T00:47:12.452208Z","end":"2026-05-25T00:47:12.552509Z","steps":["trace[1579718310] 'agreement among raft nodes before linearized reading' (duration: 100.235638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:12.552937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.995673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-25T00:47:12.552992Z","caller":"traceutil/trace.go:171","msg":"trace[718584741] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4711; }","duration":"163.091154ms","start":"2026-05-25T00:47:12.38989Z","end":"2026-05-25T00:47:12.552981Z","steps":["trace[718584741] 'agreement among raft nodes before linearized reading' (duration: 162.952702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:12.554378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.358049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-05-25T00:47:12.554505Z","caller":"traceutil/trace.go:171","msg":"trace[1621750546] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4711; }","duration":"158.704856ms","start":"2026-05-25T00:47:12.395772Z","end":"2026-05-25T00:47:12.554477Z","steps":["trace[1621750546] 'agreement among raft nodes before linearized reading' (duration: 156.840138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:48:19.553257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.621452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-25T00:48:19.553609Z","caller":"traceutil/trace.go:171","msg":"trace[1408953783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5348; }","duration":"102.08714ms","start":"2026-05-25T00:48:19.451493Z","end":"2026-05-25T00:48:19.55358Z","steps":["trace[1408953783] 'range keys from in-memory index tree' (duration: 101.288877ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:45.032721Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2866} {"level":"info","ts":"2026-05-25T00:48:45.115893Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2866,"took":"81.231634ms","hash":2047460668,"current-db-size-bytes":53956608,"current-db-size":"54 MB","current-db-size-in-use-bytes":29040640,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-25T00:48:45.115982Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2047460668,"revision":2866,"compact-revision":-1} {"level":"info","ts":"2026-05-25T00:53:45.039299Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5534} {"level":"info","ts":"2026-05-25T00:53:45.139888Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5534,"took":"98.404107ms","hash":3193289473,"current-db-size-bytes":53956608,"current-db-size":"54 MB","current-db-size-in-use-bytes":18247680,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-25T00:53:45.139959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3193289473,"revision":5534,"compact-revision":2866}