{"level":"warn","ts":"2026-05-04T18:55:13.743008Z","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-04T18:55:13.743114Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-04T18:55:13.743205Z","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-04T18:55:13.74322Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-04T18:55:13.743241Z","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-04T18:55:13.744089Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-05-04T18:55:13.744189Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-04T18:55:13.748661Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.182962ms"} {"level":"info","ts":"2026-05-04T18:55:13.758502Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-05-04T18:55:13.758592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-05-04T18:55:13.758612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-05-04T18:55:13.758625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-04T18:55:13.758633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-05-04T18:55:13.758906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-05-04T18:55:13.767996Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-04T18:55:13.770652Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-04T18:55:13.772833Z","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-04T18:55:13.777886Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-04T18:55:13.778569Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-04T18:55:13.77962Z","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-04T18:55:13.779799Z","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-04T18:55:13.779826Z","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-04T18:55:13.780023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-05-04T18:55:13.780279Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-04T18:55:13.783466Z","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-04T18:55:13.78365Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-04T18:55:13.783704Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-04T18:55:13.78468Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-04T18:55:13.78463Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-04T18:55:14.159718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-05-04T18:55:14.159824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-04T18:55:14.15999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-05-04T18:55:14.160027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-05-04T18:55:14.160039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-04T18:55:14.16006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-05-04T18:55:14.160075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-04T18:55:14.161055Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-04T18:55:14.161101Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T18:55:14.161058Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T18:55:14.161434Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:55:14.162305Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:55:14.162391Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:55:14.162417Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:55:14.164827Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-04T18:55:14.165035Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-04T18:55:14.165084Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-04T18:55:14.165406Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-05-04T18:55:20.616805Z","caller":"traceutil/trace.go:171","msg":"trace[1432144768] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"126.767056ms","start":"2026-05-04T18:55:20.490022Z","end":"2026-05-04T18:55:20.616789Z","steps":["trace[1432144768] 'process raft request' (duration: 126.681023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:55:20.803054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.077184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-04T18:55:20.803182Z","caller":"traceutil/trace.go:171","msg":"trace[1078546356] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:273; }","duration":"181.258989ms","start":"2026-05-04T18:55:20.621904Z","end":"2026-05-04T18:55:20.803163Z","steps":["trace[1078546356] 'range keys from in-memory index tree' (duration: 181.015422ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:55:21.07746Z","caller":"traceutil/trace.go:171","msg":"trace[25969258] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"270.955614ms","start":"2026-05-04T18:55:20.806489Z","end":"2026-05-04T18:55:21.077444Z","steps":["trace[25969258] 'process raft request' (duration: 270.566652ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:55:21.181487Z","caller":"traceutil/trace.go:171","msg":"trace[761780261] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"100.619601ms","start":"2026-05-04T18:55:21.08084Z","end":"2026-05-04T18:55:21.181459Z","steps":["trace[761780261] 'process raft request' (duration: 91.720529ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:55:42.753593Z","caller":"traceutil/trace.go:171","msg":"trace[1251637811] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:446; }","duration":"131.988202ms","start":"2026-05-04T18:55:42.621579Z","end":"2026-05-04T18:55:42.753567Z","steps":["trace[1251637811] 'read index received' (duration: 61.207384ms)","trace[1251637811] 'applied index is now lower than readState.Index' (duration: 70.779458ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:55:42.753722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.127376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:55:42.753753Z","caller":"traceutil/trace.go:171","msg":"trace[166944855] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:429; }","duration":"132.207728ms","start":"2026-05-04T18:55:42.621536Z","end":"2026-05-04T18:55:42.753744Z","steps":["trace[166944855] 'agreement among raft nodes before linearized reading' (duration: 132.133296ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:55:44.714266Z","caller":"traceutil/trace.go:171","msg":"trace[298526231] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"130.565289ms","start":"2026-05-04T18:55:44.583675Z","end":"2026-05-04T18:55:44.71424Z","steps":["trace[298526231] 'process raft request' (duration: 123.063501ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:55:44.714734Z","caller":"traceutil/trace.go:171","msg":"trace[1243527414] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"130.534237ms","start":"2026-05-04T18:55:44.584189Z","end":"2026-05-04T18:55:44.714723Z","steps":["trace[1243527414] 'process raft request' (duration: 129.879277ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:55:44.715063Z","caller":"traceutil/trace.go:171","msg":"trace[411464779] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"119.962912ms","start":"2026-05-04T18:55:44.595092Z","end":"2026-05-04T18:55:44.715055Z","steps":["trace[411464779] 'process raft request' (duration: 119.187558ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:55:44.715261Z","caller":"traceutil/trace.go:171","msg":"trace[1457591048] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"114.056753ms","start":"2026-05-04T18:55:44.601195Z","end":"2026-05-04T18:55:44.715251Z","steps":["trace[1457591048] 'process raft request' (duration: 113.449104ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:55:50.675721Z","caller":"traceutil/trace.go:171","msg":"trace[915582625] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"321.809708ms","start":"2026-05-04T18:55:50.353884Z","end":"2026-05-04T18:55:50.675693Z","steps":["trace[915582625] 'process raft request' (duration: 321.291831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:55:50.676261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:55:50.353854Z","time spent":"321.946472ms","remote":"127.0.0.1:37590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:55:51.347586Z","caller":"traceutil/trace.go:171","msg":"trace[99189908] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"196.569924ms","start":"2026-05-04T18:55:51.150989Z","end":"2026-05-04T18:55:51.347559Z","steps":["trace[99189908] 'read index received' (duration: 196.449941ms)","trace[99189908] 'applied index is now lower than readState.Index' (duration: 118.893µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:55:51.34769Z","caller":"traceutil/trace.go:171","msg":"trace[672241257] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"615.887605ms","start":"2026-05-04T18:55:50.731771Z","end":"2026-05-04T18:55:51.347659Z","steps":["trace[672241257] 'process raft request' (duration: 615.667568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:55:51.347809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.802671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T18:55:51.347843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:55:50.731743Z","time spent":"616.029648ms","remote":"127.0.0.1:38062","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-05-04T18:55:51.347855Z","caller":"traceutil/trace.go:171","msg":"trace[739387410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"196.893084ms","start":"2026-05-04T18:55:51.15095Z","end":"2026-05-04T18:55:51.347843Z","steps":["trace[739387410] 'agreement among raft nodes before linearized reading' (duration: 196.77026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:07.526126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:07.145674Z","time spent":"380.445689ms","remote":"127.0.0.1:37544","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-04T18:56:07.526132Z","caller":"traceutil/trace.go:171","msg":"trace[538305209] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"376.237225ms","start":"2026-05-04T18:56:07.14986Z","end":"2026-05-04T18:56:07.526097Z","steps":["trace[538305209] 'read index received' (duration: 376.013958ms)","trace[538305209] 'applied index is now lower than readState.Index' (duration: 191.146µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:56:07.526294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.42148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:56:07.526383Z","caller":"traceutil/trace.go:171","msg":"trace[189140251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"376.571295ms","start":"2026-05-04T18:56:07.149797Z","end":"2026-05-04T18:56:07.526368Z","steps":["trace[189140251] 'agreement among raft nodes before linearized reading' (duration: 376.436521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:07.526452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:07.149775Z","time spent":"376.646837ms","remote":"127.0.0.1:37516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-04T18:56:07.526677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.288832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5sjpr\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-04T18:56:07.526763Z","caller":"traceutil/trace.go:171","msg":"trace[1106112356] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5sjpr; range_end:; response_count:1; response_revision:740; }","duration":"316.452657ms","start":"2026-05-04T18:56:07.210294Z","end":"2026-05-04T18:56:07.526746Z","steps":["trace[1106112356] 'agreement among raft nodes before linearized reading' (duration: 316.069355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:07.526922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:07.210273Z","time spent":"316.628993ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5sjpr\" "} {"level":"warn","ts":"2026-05-04T18:57:20.955874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.990775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8127311e-8521-457b-a9d4-94348b41a7f8\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-04T18:57:20.955989Z","caller":"traceutil/trace.go:171","msg":"trace[1017213945] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8127311e-8521-457b-a9d4-94348b41a7f8; range_end:; response_count:1; response_revision:1800; }","duration":"411.13943ms","start":"2026-05-04T18:57:20.544819Z","end":"2026-05-04T18:57:20.955959Z","steps":["trace[1017213945] 'range keys from in-memory index tree' (duration: 410.851061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:20.956728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.378228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-04T18:57:20.956774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:20.544785Z","time spent":"411.967756ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8127311e-8521-457b-a9d4-94348b41a7f8\" "} {"level":"info","ts":"2026-05-04T18:57:20.957081Z","caller":"traceutil/trace.go:171","msg":"trace[589372346] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"402.5131ms","start":"2026-05-04T18:57:20.554538Z","end":"2026-05-04T18:57:20.957051Z","steps":["trace[589372346] 'process raft request' (duration: 53.600085ms)","trace[589372346] 'compare' (duration: 347.743148ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:57:20.957175Z","caller":"traceutil/trace.go:171","msg":"trace[799907979] linearizableReadLoop","detail":"{readStateIndex:1867; appliedIndex:1866; }","duration":"354.090592ms","start":"2026-05-04T18:57:20.603062Z","end":"2026-05-04T18:57:20.957152Z","steps":["trace[799907979] 'read index received' (duration: 5.042722ms)","trace[799907979] 'applied index is now lower than readState.Index' (duration: 349.045579ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:57:20.957139Z","caller":"traceutil/trace.go:171","msg":"trace[1719914925] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"298.063308ms","start":"2026-05-04T18:57:20.659057Z","end":"2026-05-04T18:57:20.95712Z","steps":["trace[1719914925] 'process raft request' (duration: 297.945934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:20.957266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:20.554526Z","time spent":"402.649765ms","remote":"127.0.0.1:37790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:57:20.957365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.295839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8127311e-8521-457b-a9d4-94348b41a7f8\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-04T18:57:20.957442Z","caller":"traceutil/trace.go:171","msg":"trace[1671530577] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8127311e-8521-457b-a9d4-94348b41a7f8; range_end:; response_count:1; response_revision:1802; }","duration":"354.420822ms","start":"2026-05-04T18:57:20.603004Z","end":"2026-05-04T18:57:20.957425Z","steps":["trace[1671530577] 'agreement among raft nodes before linearized reading' (duration: 354.225476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:20.957487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:20.602982Z","time spent":"354.496685ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8127311e-8521-457b-a9d4-94348b41a7f8\" "} {"level":"info","ts":"2026-05-04T18:57:21.227962Z","caller":"traceutil/trace.go:171","msg":"trace[2135791404] linearizableReadLoop","detail":"{readStateIndex:1871; appliedIndex:1870; }","duration":"142.871921ms","start":"2026-05-04T18:57:21.085068Z","end":"2026-05-04T18:57:21.22794Z","steps":["trace[2135791404] 'read index received' (duration: 142.649244ms)","trace[2135791404] 'applied index is now lower than readState.Index' (duration: 221.427µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:57:21.228049Z","caller":"traceutil/trace.go:171","msg":"trace[1632541194] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"228.669434ms","start":"2026-05-04T18:57:20.999336Z","end":"2026-05-04T18:57:21.228006Z","steps":["trace[1632541194] 'process raft request' (duration: 228.443557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:21.228178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.089288ms","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-04T18:57:21.22822Z","caller":"traceutil/trace.go:171","msg":"trace[1585454430] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1805; }","duration":"143.183271ms","start":"2026-05-04T18:57:21.085021Z","end":"2026-05-04T18:57:21.228204Z","steps":["trace[1585454430] 'agreement among raft nodes before linearized reading' (duration: 143.036636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:21.228255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.501562ms","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-04T18:57:21.228305Z","caller":"traceutil/trace.go:171","msg":"trace[1997354517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1805; }","duration":"102.606105ms","start":"2026-05-04T18:57:21.125687Z","end":"2026-05-04T18:57:21.228293Z","steps":["trace[1997354517] 'agreement among raft nodes before linearized reading' (duration: 102.45779ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:21.498439Z","caller":"traceutil/trace.go:171","msg":"trace[1481874394] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"263.825427ms","start":"2026-05-04T18:57:21.234588Z","end":"2026-05-04T18:57:21.498413Z","steps":["trace[1481874394] 'process raft request' (duration: 263.784815ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:21.49847Z","caller":"traceutil/trace.go:171","msg":"trace[1096794982] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"264.981444ms","start":"2026-05-04T18:57:21.233458Z","end":"2026-05-04T18:57:21.498439Z","steps":["trace[1096794982] 'process raft request' (duration: 264.834148ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:21.498431Z","caller":"traceutil/trace.go:171","msg":"trace[1779566481] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"265.068915ms","start":"2026-05-04T18:57:21.233322Z","end":"2026-05-04T18:57:21.49839Z","steps":["trace[1779566481] 'process raft request' (duration: 237.823129ms)","trace[1779566481] 'compare' (duration: 27.014099ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:57:21.49863Z","caller":"traceutil/trace.go:171","msg":"trace[1765480170] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"265.127239ms","start":"2026-05-04T18:57:21.233492Z","end":"2026-05-04T18:57:21.498619Z","steps":["trace[1765480170] 'process raft request' (duration: 264.849209ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:27.172294Z","caller":"traceutil/trace.go:171","msg":"trace[1463270548] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1937; }","duration":"181.031574ms","start":"2026-05-04T18:57:26.991238Z","end":"2026-05-04T18:57:27.172269Z","steps":["trace[1463270548] 'read index received' (duration: 33.059265ms)","trace[1463270548] 'applied index is now lower than readState.Index' (duration: 147.971049ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:57:27.172409Z","caller":"traceutil/trace.go:171","msg":"trace[423668765] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"191.834202ms","start":"2026-05-04T18:57:26.980539Z","end":"2026-05-04T18:57:27.172373Z","steps":["trace[423668765] 'process raft request' (duration: 189.578839ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:27.172458Z","caller":"traceutil/trace.go:171","msg":"trace[1724150813] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"190.453846ms","start":"2026-05-04T18:57:26.981989Z","end":"2026-05-04T18:57:27.172442Z","steps":["trace[1724150813] 'process raft request' (duration: 190.191348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:27.172662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.412435ms","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-04T18:57:27.172748Z","caller":"traceutil/trace.go:171","msg":"trace[1940607845] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1872; }","duration":"181.532439ms","start":"2026-05-04T18:57:26.991195Z","end":"2026-05-04T18:57:27.172728Z","steps":["trace[1940607845] 'agreement among raft nodes before linearized reading' (duration: 181.265561ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:33.37704Z","caller":"traceutil/trace.go:171","msg":"trace[1989140317] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"188.011536ms","start":"2026-05-04T18:57:33.189004Z","end":"2026-05-04T18:57:33.377016Z","steps":["trace[1989140317] 'process raft request' (duration: 187.857285ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:33.614457Z","caller":"traceutil/trace.go:171","msg":"trace[1887656469] linearizableReadLoop","detail":"{readStateIndex:1981; appliedIndex:1979; }","duration":"303.803484ms","start":"2026-05-04T18:57:33.310625Z","end":"2026-05-04T18:57:33.614429Z","steps":["trace[1887656469] 'read index received' (duration: 66.318953ms)","trace[1887656469] 'applied index is now lower than readState.Index' (duration: 237.483181ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:57:33.614484Z","caller":"traceutil/trace.go:171","msg":"trace[1682947993] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"424.014726ms","start":"2026-05-04T18:57:33.190438Z","end":"2026-05-04T18:57:33.614453Z","steps":["trace[1682947993] 'process raft request' (duration: 391.241071ms)","trace[1682947993] 'compare' (duration: 32.373191ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:57:33.614622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:33.190414Z","time spent":"424.130646ms","remote":"127.0.0.1:37790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:57:33.614722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.472356ms","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-04T18:57:33.614758Z","caller":"traceutil/trace.go:171","msg":"trace[1690662667] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1911; }","duration":"301.548486ms","start":"2026-05-04T18:57:33.313198Z","end":"2026-05-04T18:57:33.614747Z","steps":["trace[1690662667] 'agreement among raft nodes before linearized reading' (duration: 301.400905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:33.614787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:33.313182Z","time spent":"301.598906ms","remote":"127.0.0.1:38508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-04T18:57:33.614669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.032525ms","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-04T18:57:33.614983Z","caller":"traceutil/trace.go:171","msg":"trace[399859758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1911; }","duration":"304.387828ms","start":"2026-05-04T18:57:33.310575Z","end":"2026-05-04T18:57:33.614963Z","steps":["trace[399859758] 'agreement among raft nodes before linearized reading' (duration: 303.973075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:33.615031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:33.310558Z","time spent":"304.459689ms","remote":"127.0.0.1:37790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-04T18:57:54.65278Z","caller":"traceutil/trace.go:171","msg":"trace[145781174] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"125.857094ms","start":"2026-05-04T18:57:54.526889Z","end":"2026-05-04T18:57:54.652746Z","steps":["trace[145781174] 'process raft request' (duration: 125.750213ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:55.33568Z","caller":"traceutil/trace.go:171","msg":"trace[703386520] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"149.386768ms","start":"2026-05-04T18:57:55.18627Z","end":"2026-05-04T18:57:55.335656Z","steps":["trace[703386520] 'process raft request' (duration: 149.224227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:55.863055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.801661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:57:55.863161Z","caller":"traceutil/trace.go:171","msg":"trace[803629142] linearizableReadLoop","detail":"{readStateIndex:2121; appliedIndex:2120; }","duration":"137.754467ms","start":"2026-05-04T18:57:55.725389Z","end":"2026-05-04T18:57:55.863143Z","steps":["trace[803629142] 'read index received' (duration: 5.658574ms)","trace[803629142] 'applied index is now lower than readState.Index' (duration: 132.094373ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:57:55.863518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.13142ms","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-04T18:57:55.86356Z","caller":"traceutil/trace.go:171","msg":"trace[1990422696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2047; }","duration":"138.210681ms","start":"2026-05-04T18:57:55.725338Z","end":"2026-05-04T18:57:55.863549Z","steps":["trace[1990422696] 'agreement among raft nodes before linearized reading' (duration: 138.03022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:55.863705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.975272ms","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-04T18:57:55.863785Z","caller":"traceutil/trace.go:171","msg":"trace[274837476] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2047; }","duration":"108.112013ms","start":"2026-05-04T18:57:55.755655Z","end":"2026-05-04T18:57:55.863767Z","steps":["trace[274837476] 'agreement among raft nodes before linearized reading' (duration: 107.902681ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:55.863872Z","caller":"traceutil/trace.go:171","msg":"trace[33058491] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"182.95308ms","start":"2026-05-04T18:57:55.680265Z","end":"2026-05-04T18:57:55.863218Z","steps":["trace[33058491] 'process raft request' (duration: 50.859198ms)","trace[33058491] 'compare' (duration: 131.404416ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:57:56.144655Z","caller":"traceutil/trace.go:171","msg":"trace[754728106] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"276.046611ms","start":"2026-05-04T18:57:55.86852Z","end":"2026-05-04T18:57:56.144567Z","steps":["trace[754728106] 'process raft request' (duration: 275.746778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:56.447027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.517051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:57:56.447184Z","caller":"traceutil/trace.go:171","msg":"trace[1631411130] linearizableReadLoop","detail":"{readStateIndex:2124; appliedIndex:2122; }","duration":"296.746522ms","start":"2026-05-04T18:57:56.150421Z","end":"2026-05-04T18:57:56.447168Z","steps":["trace[1631411130] 'read index received' (duration: 172.011919ms)","trace[1631411130] 'applied index is now lower than readState.Index' (duration: 124.733543ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:57:56.447309Z","caller":"traceutil/trace.go:171","msg":"trace[1594356035] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"341.967878ms","start":"2026-05-04T18:57:56.105289Z","end":"2026-05-04T18:57:56.447257Z","steps":["trace[1594356035] 'process raft request' (duration: 217.129464ms)","trace[1594356035] 'compare' (duration: 124.417ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:57:56.447367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:56.105257Z","time spent":"342.079458ms","remote":"127.0.0.1:37790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:57:56.447538Z","caller":"traceutil/trace.go:171","msg":"trace[1589948823] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"298.921512ms","start":"2026-05-04T18:57:56.148607Z","end":"2026-05-04T18:57:56.447528Z","steps":["trace[1589948823] 'process raft request' (duration: 298.515138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:56.447701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.445337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-04T18:57:56.447687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.248596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:57:56.447743Z","caller":"traceutil/trace.go:171","msg":"trace[961049292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2050; }","duration":"191.521497ms","start":"2026-05-04T18:57:56.256209Z","end":"2026-05-04T18:57:56.447731Z","steps":["trace[961049292] 'agreement among raft nodes before linearized reading' (duration: 191.388036ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:57:56.447767Z","caller":"traceutil/trace.go:171","msg":"trace[1427633073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2050; }","duration":"297.354347ms","start":"2026-05-04T18:57:56.150389Z","end":"2026-05-04T18:57:56.447743Z","steps":["trace[1427633073] 'agreement among raft nodes before linearized reading' (duration: 297.227686ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:58:53.793483Z","caller":"traceutil/trace.go:171","msg":"trace[1095861506] transaction","detail":"{read_only:false; response_revision:2407; number_of_response:1; }","duration":"109.885432ms","start":"2026-05-04T18:58:53.683572Z","end":"2026-05-04T18:58:53.793458Z","steps":["trace[1095861506] 'process raft request' (duration: 109.7403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:59:02.417865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.284099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:59:02.417956Z","caller":"traceutil/trace.go:171","msg":"trace[180375093] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:2508; }","duration":"259.412751ms","start":"2026-05-04T18:59:02.158525Z","end":"2026-05-04T18:59:02.417938Z","steps":["trace[180375093] 'count revisions from in-memory index tree' (duration: 259.238879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:59:02.418516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.108031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-04T18:59:02.418677Z","caller":"traceutil/trace.go:171","msg":"trace[411370911] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2508; }","duration":"194.298763ms","start":"2026-05-04T18:59:02.224344Z","end":"2026-05-04T18:59:02.418643Z","steps":["trace[411370911] 'range keys from in-memory index tree' (duration: 193.391561ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:59:35.051538Z","caller":"traceutil/trace.go:171","msg":"trace[534942895] transaction","detail":"{read_only:false; response_revision:2688; number_of_response:1; }","duration":"151.869821ms","start":"2026-05-04T18:59:34.899646Z","end":"2026-05-04T18:59:35.051516Z","steps":["trace[534942895] 'process raft request' (duration: 151.726368ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:59:35.10177Z","caller":"traceutil/trace.go:171","msg":"trace[1430252137] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"200.014016ms","start":"2026-05-04T18:59:34.901737Z","end":"2026-05-04T18:59:35.101751Z","steps":["trace[1430252137] 'process raft request' (duration: 184.556577ms)","trace[1430252137] 'compare' (duration: 15.378938ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:59:35.291951Z","caller":"traceutil/trace.go:171","msg":"trace[1066258568] linearizableReadLoop","detail":"{readStateIndex:2787; appliedIndex:2786; }","duration":"118.496059ms","start":"2026-05-04T18:59:35.17343Z","end":"2026-05-04T18:59:35.291927Z","steps":["trace[1066258568] 'read index received' (duration: 113.577519ms)","trace[1066258568] 'applied index is now lower than readState.Index' (duration: 4.91733ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:59:35.292155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.715692ms","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-04T18:59:35.292393Z","caller":"traceutil/trace.go:171","msg":"trace[1585500690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2690; }","duration":"118.985967ms","start":"2026-05-04T18:59:35.173393Z","end":"2026-05-04T18:59:35.292379Z","steps":["trace[1585500690] 'agreement among raft nodes before linearized reading' (duration: 118.631901ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:59:35.292148Z","caller":"traceutil/trace.go:171","msg":"trace[1330478860] transaction","detail":"{read_only:false; response_revision:2690; number_of_response:1; }","duration":"119.648758ms","start":"2026-05-04T18:59:35.172472Z","end":"2026-05-04T18:59:35.292121Z","steps":["trace[1330478860] 'process raft request' (duration: 114.488164ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:59:35.509321Z","caller":"traceutil/trace.go:171","msg":"trace[497936491] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"212.801887ms","start":"2026-05-04T18:59:35.296483Z","end":"2026-05-04T18:59:35.509285Z","steps":["trace[497936491] 'process raft request' (duration: 164.461538ms)","trace[497936491] 'compare' (duration: 48.016203ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:59:35.631295Z","caller":"traceutil/trace.go:171","msg":"trace[1371834233] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"111.964003ms","start":"2026-05-04T18:59:35.519314Z","end":"2026-05-04T18:59:35.631278Z","steps":["trace[1371834233] 'process raft request' (duration: 108.586569ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:09.476698Z","caller":"traceutil/trace.go:171","msg":"trace[158336595] transaction","detail":"{read_only:false; response_revision:4406; number_of_response:1; }","duration":"122.336528ms","start":"2026-05-04T19:03:09.354343Z","end":"2026-05-04T19:03:09.476679Z","steps":["trace[158336595] 'process raft request' (duration: 122.218115ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:20.488631Z","caller":"traceutil/trace.go:171","msg":"trace[1118331282] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"208.727302ms","start":"2026-05-04T19:03:20.279873Z","end":"2026-05-04T19:03:20.4886Z","steps":["trace[1118331282] 'process raft request' (duration: 208.548867ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:20.488701Z","caller":"traceutil/trace.go:171","msg":"trace[1609184339] linearizableReadLoop","detail":"{readStateIndex:4833; appliedIndex:4833; }","duration":"172.489508ms","start":"2026-05-04T19:03:20.316183Z","end":"2026-05-04T19:03:20.488672Z","steps":["trace[1609184339] 'read index received' (duration: 172.480468ms)","trace[1609184339] 'applied index is now lower than readState.Index' (duration: 7.12µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:03:20.488977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.781665ms","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-05-04T19:03:20.489012Z","caller":"traceutil/trace.go:171","msg":"trace[2058767557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4658; }","duration":"172.868327ms","start":"2026-05-04T19:03:20.316133Z","end":"2026-05-04T19:03:20.489002Z","steps":["trace[2058767557] 'agreement among raft nodes before linearized reading' (duration: 172.662722ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:20.588564Z","caller":"traceutil/trace.go:171","msg":"trace[194767028] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"107.584644ms","start":"2026-05-04T19:03:20.480944Z","end":"2026-05-04T19:03:20.588528Z","steps":["trace[194767028] 'process raft request' (duration: 102.129413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:20.588602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.142261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-04T19:03:20.588683Z","caller":"traceutil/trace.go:171","msg":"trace[1450055875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4659; }","duration":"245.256954ms","start":"2026-05-04T19:03:20.343406Z","end":"2026-05-04T19:03:20.588663Z","steps":["trace[1450055875] 'agreement among raft nodes before linearized reading' (duration: 244.997727ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:04:00.943633Z","caller":"traceutil/trace.go:171","msg":"trace[1838383532] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"100.26158ms","start":"2026-05-04T19:04:00.843338Z","end":"2026-05-04T19:04:00.943599Z","steps":["trace[1838383532] 'process raft request' (duration: 77.753139ms)","trace[1838383532] 'compare' (duration: 22.230764ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:05:14.353817Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2896} {"level":"info","ts":"2026-05-04T19:05:14.440968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2896,"took":"85.71376ms","hash":2296347290,"current-db-size-bytes":54157312,"current-db-size":"54 MB","current-db-size-in-use-bytes":29364224,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-04T19:05:14.441165Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2296347290,"revision":2896,"compact-revision":-1} {"level":"info","ts":"2026-05-04T19:07:31.799659Z","caller":"traceutil/trace.go:171","msg":"trace[1723969174] transaction","detail":"{read_only:false; response_revision:6562; number_of_response:1; }","duration":"116.501472ms","start":"2026-05-04T19:07:31.683137Z","end":"2026-05-04T19:07:31.799639Z","steps":["trace[1723969174] 'process raft request' (duration: 57.714586ms)","trace[1723969174] 'compare' (duration: 58.705064ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:10:14.367549Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5609} {"level":"info","ts":"2026-05-04T19:10:14.640817Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5609,"took":"271.780358ms","hash":1181885408,"current-db-size-bytes":54157312,"current-db-size":"54 MB","current-db-size-in-use-bytes":17899520,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-04T19:10:14.640903Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1181885408,"revision":5609,"compact-revision":2896}