{"level":"warn","ts":"2026-04-25T08:45:03.517912Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-25T08:45:03.517989Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-25T08:45:03.518048Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-25T08:45:03.518061Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-25T08:45:03.518077Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-25T08:45:03.518759Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-04-25T08:45:03.51884Z","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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-25T08:45:03.522865Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.775074ms"} {"level":"info","ts":"2026-04-25T08:45:03.53046Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-04-25T08:45:03.530566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-04-25T08:45:03.530593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-04-25T08:45:03.530612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-25T08:45:03.53063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-04-25T08:45:03.530685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-04-25T08:45:03.53761Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-25T08:45:03.538616Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-25T08:45:03.540885Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-25T08:45:03.543404Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-25T08:45:03.543548Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-25T08:45:03.543646Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-25T08:45:03.543724Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-25T08:45:03.543747Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-25T08:45:03.547894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-04-25T08:45:03.548493Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-25T08:45:03.549907Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-25T08:45:03.550105Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-25T08:45:03.550136Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-25T08:45:03.550217Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-25T08:45:03.550264Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-25T08:45:04.530873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-04-25T08:45:04.530931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-25T08:45:04.530954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-04-25T08:45:04.530972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-04-25T08:45:04.530984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-25T08:45:04.531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-04-25T08:45:04.531073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-25T08:45:04.532317Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-04-25T08:45:04.53238Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-25T08:45:04.532463Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-25T08:45:04.532454Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T08:45:04.532696Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-25T08:45:04.532745Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-25T08:45:04.533776Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T08:45:04.533916Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T08:45:04.533989Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T08:45:04.536561Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-25T08:45:04.537367Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"warn","ts":"2026-04-25T08:45:07.215091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.092123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-25T08:45:07.215232Z","caller":"traceutil/trace.go:171","msg":"trace[879032866] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"354.097391ms","start":"2026-04-25T08:45:06.861115Z","end":"2026-04-25T08:45:07.215213Z","steps":["trace[879032866] 'read index received' (duration: 156.34854ms)","trace[879032866] 'applied index is now lower than readState.Index' (duration: 197.746951ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T08:45:07.21553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.53514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-25T08:45:07.215614Z","caller":"traceutil/trace.go:171","msg":"trace[1038949177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"308.677321ms","start":"2026-04-25T08:45:06.906906Z","end":"2026-04-25T08:45:07.215583Z","steps":["trace[1038949177] 'agreement among raft nodes before linearized reading' (duration: 308.465828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:07.215552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.372935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-25T08:45:07.215666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T08:45:06.906887Z","time spent":"308.768913ms","remote":"127.0.0.1:41924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-25T08:45:07.215762Z","caller":"traceutil/trace.go:171","msg":"trace[935155464] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:72; }","duration":"354.628858ms","start":"2026-04-25T08:45:06.861042Z","end":"2026-04-25T08:45:07.215671Z","steps":["trace[935155464] 'agreement among raft nodes before linearized reading' (duration: 354.272533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:07.215905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T08:45:06.861035Z","time spent":"354.851471ms","remote":"127.0.0.1:42308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"info","ts":"2026-04-25T08:45:07.215928Z","caller":"traceutil/trace.go:171","msg":"trace[1336798894] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"355.496779ms","start":"2026-04-25T08:45:06.860327Z","end":"2026-04-25T08:45:07.215824Z","steps":["trace[1336798894] 'process raft request' (duration: 157.106679ms)","trace[1336798894] 'compare' (duration: 196.86832ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T08:45:07.216092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T08:45:06.860309Z","time spent":"355.712823ms","remote":"127.0.0.1:42330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-25T08:45:15.249162Z","caller":"traceutil/trace.go:171","msg":"trace[976859321] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:337; }","duration":"115.191145ms","start":"2026-04-25T08:45:15.133948Z","end":"2026-04-25T08:45:15.249139Z","steps":["trace[976859321] 'read index received' (duration: 27.344184ms)","trace[976859321] 'applied index is now lower than readState.Index' (duration: 87.846221ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T08:45:15.24932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.334986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3215"} {"level":"info","ts":"2026-04-25T08:45:15.2494Z","caller":"traceutil/trace.go:171","msg":"trace[1075016223] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:327; }","duration":"115.485697ms","start":"2026-04-25T08:45:15.133897Z","end":"2026-04-25T08:45:15.249382Z","steps":["trace[1075016223] 'agreement among raft nodes before linearized reading' (duration: 115.319045ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:15.24954Z","caller":"traceutil/trace.go:171","msg":"trace[2066290551] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"143.482699ms","start":"2026-04-25T08:45:15.106016Z","end":"2026-04-25T08:45:15.249499Z","steps":["trace[2066290551] 'process raft request' (duration: 55.243824ms)","trace[2066290551] 'compare' (duration: 87.777669ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T08:45:15.442686Z","caller":"traceutil/trace.go:171","msg":"trace[1899699838] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"184.695337ms","start":"2026-04-25T08:45:15.25796Z","end":"2026-04-25T08:45:15.442655Z","steps":["trace[1899699838] 'process raft request' (duration: 94.55259ms)","trace[1899699838] 'compare' (duration: 89.911075ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T08:45:15.4427Z","caller":"traceutil/trace.go:171","msg":"trace[811793459] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"177.224309ms","start":"2026-04-25T08:45:15.265453Z","end":"2026-04-25T08:45:15.442677Z","steps":["trace[811793459] 'process raft request' (duration: 177.185328ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:15.442751Z","caller":"traceutil/trace.go:171","msg":"trace[1918610212] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"179.88293ms","start":"2026-04-25T08:45:15.262839Z","end":"2026-04-25T08:45:15.442722Z","steps":["trace[1918610212] 'process raft request' (duration: 179.722218ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:19.762853Z","caller":"traceutil/trace.go:171","msg":"trace[334472308] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"115.856937ms","start":"2026-04-25T08:45:19.646972Z","end":"2026-04-25T08:45:19.762829Z","steps":["trace[334472308] 'process raft request' (duration: 115.717956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:28.764859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.957481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:7352"} {"level":"info","ts":"2026-04-25T08:45:28.765098Z","caller":"traceutil/trace.go:171","msg":"trace[599642471] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"198.199645ms","start":"2026-04-25T08:45:28.56685Z","end":"2026-04-25T08:45:28.76505Z","steps":["trace[599642471] 'process raft request' (duration: 198.097324ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:28.765175Z","caller":"traceutil/trace.go:171","msg":"trace[1670407127] transaction","detail":"{read_only:false; number_of_response:1; response_revision:469; }","duration":"198.385057ms","start":"2026-04-25T08:45:28.566756Z","end":"2026-04-25T08:45:28.765141Z","steps":["trace[1670407127] 'process raft request' (duration: 66.965501ms)","trace[1670407127] 'compare' (duration: 130.89048ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T08:45:28.767833Z","caller":"traceutil/trace.go:171","msg":"trace[162522812] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"180.203013ms","start":"2026-04-25T08:45:28.587611Z","end":"2026-04-25T08:45:28.767814Z","steps":["trace[162522812] 'process raft request' (duration: 180.170483ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:28.768034Z","caller":"traceutil/trace.go:171","msg":"trace[1003640279] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"190.547657ms","start":"2026-04-25T08:45:28.577473Z","end":"2026-04-25T08:45:28.76802Z","steps":["trace[1003640279] 'process raft request' (duration: 190.141733ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:28.768088Z","caller":"traceutil/trace.go:171","msg":"trace[492058906] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"190.703249ms","start":"2026-04-25T08:45:28.577359Z","end":"2026-04-25T08:45:28.768062Z","steps":["trace[492058906] 'process raft request' (duration: 190.166754ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:28.768109Z","caller":"traceutil/trace.go:171","msg":"trace[116087282] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"181.596038ms","start":"2026-04-25T08:45:28.586504Z","end":"2026-04-25T08:45:28.7681Z","steps":["trace[116087282] 'process raft request' (duration: 181.153764ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:28.768261Z","caller":"traceutil/trace.go:171","msg":"trace[1747409794] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"181.678399ms","start":"2026-04-25T08:45:28.586573Z","end":"2026-04-25T08:45:28.768252Z","steps":["trace[1747409794] 'process raft request' (duration: 181.103273ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:28.768321Z","caller":"traceutil/trace.go:171","msg":"trace[2030769463] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"181.186354ms","start":"2026-04-25T08:45:28.587123Z","end":"2026-04-25T08:45:28.768309Z","steps":["trace[2030769463] 'process raft request' (duration: 180.619729ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:28.768482Z","caller":"traceutil/trace.go:171","msg":"trace[359406168] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"181.80954ms","start":"2026-04-25T08:45:28.586662Z","end":"2026-04-25T08:45:28.768471Z","steps":["trace[359406168] 'process raft request' (duration: 181.042032ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:28.778491Z","caller":"traceutil/trace.go:171","msg":"trace[300778763] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"129.8724ms","start":"2026-04-25T08:45:28.64859Z","end":"2026-04-25T08:45:28.778462Z","steps":["trace[300778763] 'process raft request' (duration: 124.787109ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:29.676743Z","caller":"traceutil/trace.go:171","msg":"trace[629097934] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"119.3054ms","start":"2026-04-25T08:45:29.557417Z","end":"2026-04-25T08:45:29.676722Z","steps":["trace[629097934] 'process raft request' (duration: 61.844572ms)","trace[629097934] 'compare' (duration: 57.076644ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T08:45:34.081315Z","caller":"traceutil/trace.go:171","msg":"trace[298759013] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"101.469835ms","start":"2026-04-25T08:45:33.97981Z","end":"2026-04-25T08:45:34.08128Z","steps":["trace[298759013] 'compare' (duration: 94.722481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:35.337692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.941429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T08:45:35.33779Z","caller":"traceutil/trace.go:171","msg":"trace[615327072] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:561; }","duration":"227.119611ms","start":"2026-04-25T08:45:35.110652Z","end":"2026-04-25T08:45:35.337772Z","steps":["trace[615327072] 'range keys from in-memory index tree' (duration: 226.850158ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:35.989141Z","caller":"traceutil/trace.go:171","msg":"trace[1959735489] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"262.889443ms","start":"2026-04-25T08:45:35.726225Z","end":"2026-04-25T08:45:35.989114Z","steps":["trace[1959735489] 'process raft request' (duration: 262.314268ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:35.989485Z","caller":"traceutil/trace.go:171","msg":"trace[977903037] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:585; }","duration":"186.165741ms","start":"2026-04-25T08:45:35.803245Z","end":"2026-04-25T08:45:35.989411Z","steps":["trace[977903037] 'read index received' (duration: 186.148501ms)","trace[977903037] 'applied index is now lower than readState.Index' (duration: 15.46µs)"],"step_count":2} {"level":"warn","ts":"2026-04-25T08:45:35.989629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.373192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7mp8h\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-25T08:45:35.989713Z","caller":"traceutil/trace.go:171","msg":"trace[2087998637] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7mp8h; range_end:; response_count:1; response_revision:562; }","duration":"186.486943ms","start":"2026-04-25T08:45:35.803211Z","end":"2026-04-25T08:45:35.989698Z","steps":["trace[2087998637] 'agreement among raft nodes before linearized reading' (duration: 186.288921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:36.620104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513055660781217,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-25T08:45:37.031129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.713299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-25T08:45:37.031482Z","caller":"traceutil/trace.go:171","msg":"trace[1279109330] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:586; }","duration":"912.286656ms","start":"2026-04-25T08:45:36.119171Z","end":"2026-04-25T08:45:37.031457Z","steps":["trace[1279109330] 'read index received' (duration: 691.175623ms)","trace[1279109330] 'applied index is now lower than readState.Index' (duration: 221.109873ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T08:45:37.031522Z","caller":"traceutil/trace.go:171","msg":"trace[596588885] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"980.19653ms","start":"2026-04-25T08:45:36.051307Z","end":"2026-04-25T08:45:37.031503Z","steps":["trace[596588885] 'process raft request' (duration: 979.939988ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:37.031529Z","caller":"traceutil/trace.go:171","msg":"trace[328276757] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.027167933s","start":"2026-04-25T08:45:36.004168Z","end":"2026-04-25T08:45:37.031336Z","steps":["trace[328276757] 'process raft request' (duration: 806.154451ms)","trace[328276757] 'compare' (duration: 220.584138ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T08:45:37.031639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T08:45:36.051284Z","time spent":"980.292731ms","remote":"127.0.0.1:42208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-25T08:45:37.03168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.503688ms","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-04-25T08:45:37.031701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T08:45:36.004147Z","time spent":"1.027478566s","remote":"127.0.0.1:42148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T08:45:37.031736Z","caller":"traceutil/trace.go:171","msg":"trace[1373251882] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:565; }","duration":"912.603898ms","start":"2026-04-25T08:45:36.119118Z","end":"2026-04-25T08:45:37.031722Z","steps":["trace[1373251882] 'agreement among raft nodes before linearized reading' (duration: 912.409286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:37.031775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T08:45:36.119097Z","time spent":"912.671228ms","remote":"127.0.0.1:42224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-25T08:45:37.05924Z","caller":"traceutil/trace.go:171","msg":"trace[233391692] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"153.848861ms","start":"2026-04-25T08:45:36.905375Z","end":"2026-04-25T08:45:37.059223Z","steps":["trace[233391692] 'process raft request' (duration: 153.436867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:37.059246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.879125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-25T08:45:37.05931Z","caller":"traceutil/trace.go:171","msg":"trace[1277001634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"752.977927ms","start":"2026-04-25T08:45:36.306315Z","end":"2026-04-25T08:45:37.059293Z","steps":["trace[1277001634] 'agreement among raft nodes before linearized reading' (duration: 752.787545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:37.059347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T08:45:36.306298Z","time spent":"753.041457ms","remote":"127.0.0.1:42224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-25T08:45:37.059498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.426657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-25T08:45:37.059529Z","caller":"traceutil/trace.go:171","msg":"trace[1165629889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:566; }","duration":"807.489048ms","start":"2026-04-25T08:45:36.252032Z","end":"2026-04-25T08:45:37.059521Z","steps":["trace[1165629889] 'agreement among raft nodes before linearized reading' (duration: 807.413077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:37.059738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T08:45:36.252008Z","time spent":"807.720519ms","remote":"127.0.0.1:42224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-25T08:45:37.21402Z","caller":"traceutil/trace.go:171","msg":"trace[511599669] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"155.066484ms","start":"2026-04-25T08:45:37.05893Z","end":"2026-04-25T08:45:37.213996Z","steps":["trace[511599669] 'read index received' (duration: 76.577858ms)","trace[511599669] 'applied index is now lower than readState.Index' (duration: 78.487806ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T08:45:37.214038Z","caller":"traceutil/trace.go:171","msg":"trace[1731586402] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"177.957733ms","start":"2026-04-25T08:45:37.036061Z","end":"2026-04-25T08:45:37.214018Z","steps":["trace[1731586402] 'process raft request' (duration: 99.433157ms)","trace[1731586402] 'compare' (duration: 78.336344ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T08:45:37.214258Z","caller":"traceutil/trace.go:171","msg":"trace[346084657] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"150.025168ms","start":"2026-04-25T08:45:37.064177Z","end":"2026-04-25T08:45:37.214202Z","steps":["trace[346084657] 'process raft request' (duration: 149.787426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:37.214356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.920901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-25T08:45:37.214514Z","caller":"traceutil/trace.go:171","msg":"trace[859101914] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:568; }","duration":"179.168103ms","start":"2026-04-25T08:45:37.035327Z","end":"2026-04-25T08:45:37.214495Z","steps":["trace[859101914] 'agreement among raft nodes before linearized reading' (duration: 178.744409ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:37.214536Z","caller":"traceutil/trace.go:171","msg":"trace[1046792155] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"150.245229ms","start":"2026-04-25T08:45:37.064263Z","end":"2026-04-25T08:45:37.214508Z","steps":["trace[1046792155] 'process raft request' (duration: 149.877036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:37.214838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.457598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T08:45:37.214887Z","caller":"traceutil/trace.go:171","msg":"trace[826160057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"154.567199ms","start":"2026-04-25T08:45:37.060306Z","end":"2026-04-25T08:45:37.214873Z","steps":["trace[826160057] 'agreement among raft nodes before linearized reading' (duration: 154.161906ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:37.374419Z","caller":"traceutil/trace.go:171","msg":"trace[517918556] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"148.166261ms","start":"2026-04-25T08:45:37.226216Z","end":"2026-04-25T08:45:37.374382Z","steps":["trace[517918556] 'process raft request' (duration: 144.085144ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:37.927982Z","caller":"traceutil/trace.go:171","msg":"trace[1801973880] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"103.10431ms","start":"2026-04-25T08:45:37.824831Z","end":"2026-04-25T08:45:37.927935Z","steps":["trace[1801973880] 'process raft request' (duration: 99.930482ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:39.928238Z","caller":"traceutil/trace.go:171","msg":"trace[631608214] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"104.9271ms","start":"2026-04-25T08:45:39.823284Z","end":"2026-04-25T08:45:39.928211Z","steps":["trace[631608214] 'process raft request' (duration: 95.763608ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:40.13867Z","caller":"traceutil/trace.go:171","msg":"trace[1333047837] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"152.800717ms","start":"2026-04-25T08:45:39.985845Z","end":"2026-04-25T08:45:40.138646Z","steps":["trace[1333047837] 'read index received' (duration: 39.249289ms)","trace[1333047837] 'applied index is now lower than readState.Index' (duration: 113.550458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T08:45:40.138848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.976488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T08:45:40.138903Z","caller":"traceutil/trace.go:171","msg":"trace[2066043946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"153.07184ms","start":"2026-04-25T08:45:39.985816Z","end":"2026-04-25T08:45:40.138888Z","steps":["trace[2066043946] 'agreement among raft nodes before linearized reading' (duration: 152.972119ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:40.138835Z","caller":"traceutil/trace.go:171","msg":"trace[682684994] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"153.563584ms","start":"2026-04-25T08:45:39.985219Z","end":"2026-04-25T08:45:40.138783Z","steps":["trace[682684994] 'process raft request' (duration: 153.212831ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:40.234014Z","caller":"traceutil/trace.go:171","msg":"trace[1400008830] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"203.115322ms","start":"2026-04-25T08:45:40.030878Z","end":"2026-04-25T08:45:40.233993Z","steps":["trace[1400008830] 'process raft request' (duration: 194.573666ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:40.23454Z","caller":"traceutil/trace.go:171","msg":"trace[780730582] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"202.091593ms","start":"2026-04-25T08:45:40.032418Z","end":"2026-04-25T08:45:40.23451Z","steps":["trace[780730582] 'process raft request' (duration: 201.926632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:40.234936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.811703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-25T08:45:40.235029Z","caller":"traceutil/trace.go:171","msg":"trace[797702315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"119.963543ms","start":"2026-04-25T08:45:40.115043Z","end":"2026-04-25T08:45:40.235007Z","steps":["trace[797702315] 'agreement among raft nodes before linearized reading' (duration: 119.674311ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:55.508512Z","caller":"traceutil/trace.go:171","msg":"trace[902502093] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"114.55324ms","start":"2026-04-25T08:45:55.393931Z","end":"2026-04-25T08:45:55.508485Z","steps":["trace[902502093] 'process raft request' (duration: 107.589765ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:55.550979Z","caller":"traceutil/trace.go:171","msg":"trace[771219968] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"110.434028ms","start":"2026-04-25T08:45:55.440515Z","end":"2026-04-25T08:45:55.550949Z","steps":["trace[771219968] 'process raft request' (duration: 110.312577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:56.158339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.308598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T08:45:56.158443Z","caller":"traceutil/trace.go:171","msg":"trace[1085888401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:774; }","duration":"174.45341ms","start":"2026-04-25T08:45:55.983963Z","end":"2026-04-25T08:45:56.158417Z","steps":["trace[1085888401] 'range keys from in-memory index tree' (duration: 174.189158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:45:56.159501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.165888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050268"} {"level":"info","ts":"2026-04-25T08:45:56.159579Z","caller":"traceutil/trace.go:171","msg":"trace[2074723435] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:774; }","duration":"251.277459ms","start":"2026-04-25T08:45:55.90828Z","end":"2026-04-25T08:45:56.159558Z","steps":["trace[2074723435] 'range keys from in-memory index tree' (duration: 249.746407ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:56.724928Z","caller":"traceutil/trace.go:171","msg":"trace[283022012] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"129.847506ms","start":"2026-04-25T08:45:56.595048Z","end":"2026-04-25T08:45:56.724896Z","steps":["trace[283022012] 'read index received' (duration: 121.066237ms)","trace[283022012] 'applied index is now lower than readState.Index' (duration: 8.779649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T08:45:56.725189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.114008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-04-25T08:45:56.724986Z","caller":"traceutil/trace.go:171","msg":"trace[933083123] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"140.651401ms","start":"2026-04-25T08:45:56.584302Z","end":"2026-04-25T08:45:56.724954Z","steps":["trace[933083123] 'process raft request' (duration: 131.771781ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:45:56.725449Z","caller":"traceutil/trace.go:171","msg":"trace[1350724935] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:781; }","duration":"130.214919ms","start":"2026-04-25T08:45:56.59501Z","end":"2026-04-25T08:45:56.725225Z","steps":["trace[1350724935] 'agreement among raft nodes before linearized reading' (duration: 130.000517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:47:44.359318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.320382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-25T08:47:44.35945Z","caller":"traceutil/trace.go:171","msg":"trace[2046378264] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"191.343719ms","start":"2026-04-25T08:47:44.168083Z","end":"2026-04-25T08:47:44.359427Z","steps":["trace[2046378264] 'process raft request' (duration: 72.856505ms)","trace[2046378264] 'compare' (duration: 118.157289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T08:47:51.1938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.831874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T08:47:51.193899Z","caller":"traceutil/trace.go:171","msg":"trace[443120626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1580; }","duration":"208.970815ms","start":"2026-04-25T08:47:50.984905Z","end":"2026-04-25T08:47:51.193876Z","steps":["trace[443120626] 'range keys from in-memory index tree' (duration: 208.758263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:48:26.191854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.507947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-04-25T08:48:26.192232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.429496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7e0f1207-f8c5-490f-9c45-f15d63c021ad\" ","response":"range_response_count:1 size:3616"} {"level":"info","ts":"2026-04-25T08:48:26.192306Z","caller":"traceutil/trace.go:171","msg":"trace[958666451] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7e0f1207-f8c5-490f-9c45-f15d63c021ad; range_end:; response_count:1; response_revision:2190; }","duration":"489.548818ms","start":"2026-04-25T08:48:25.70274Z","end":"2026-04-25T08:48:26.192289Z","steps":["trace[958666451] 'agreement among raft nodes before linearized reading' (duration: 489.359186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:48:26.192424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T08:48:25.702719Z","time spent":"489.661809ms","remote":"127.0.0.1:42148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3639,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7e0f1207-f8c5-490f-9c45-f15d63c021ad\" "} {"level":"info","ts":"2026-04-25T08:48:26.192069Z","caller":"traceutil/trace.go:171","msg":"trace[1180895110] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"587.813573ms","start":"2026-04-25T08:48:25.604188Z","end":"2026-04-25T08:48:26.192001Z","steps":["trace[1180895110] 'process raft request' (duration: 481.062353ms)","trace[1180895110] 'compare' (duration: 106.314535ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T08:48:26.191982Z","caller":"traceutil/trace.go:171","msg":"trace[2142403876] linearizableReadLoop","detail":"{readStateIndex:2284; appliedIndex:2283; }","duration":"489.177013ms","start":"2026-04-25T08:48:25.702788Z","end":"2026-04-25T08:48:26.191965Z","steps":["trace[2142403876] 'read index received' (duration: 382.401212ms)","trace[2142403876] 'applied index is now lower than readState.Index' (duration: 106.774361ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T08:48:26.192882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T08:48:25.604173Z","time spent":"588.607624ms","remote":"127.0.0.1:42148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6131,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T08:48:26.202306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.359523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T08:48:26.202374Z","caller":"traceutil/trace.go:171","msg":"trace[772829739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2191; }","duration":"217.465305ms","start":"2026-04-25T08:48:25.984895Z","end":"2026-04-25T08:48:26.20236Z","steps":["trace[772829739] 'agreement among raft nodes before linearized reading' (duration: 217.341803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:48:26.202625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.354598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d76b3897-bb29-493b-a18c-adf0d2c422e5\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-25T08:48:26.202684Z","caller":"traceutil/trace.go:171","msg":"trace[234885468] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d76b3897-bb29-493b-a18c-adf0d2c422e5; range_end:; response_count:1; response_revision:2191; }","duration":"222.45477ms","start":"2026-04-25T08:48:25.980208Z","end":"2026-04-25T08:48:26.202662Z","steps":["trace[234885468] 'agreement among raft nodes before linearized reading' (duration: 222.052845ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:48:37.344742Z","caller":"traceutil/trace.go:171","msg":"trace[1530266985] transaction","detail":"{read_only:false; response_revision:2310; number_of_response:1; }","duration":"110.408591ms","start":"2026-04-25T08:48:37.234305Z","end":"2026-04-25T08:48:37.344714Z","steps":["trace[1530266985] 'process raft request' (duration: 110.242369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:48:43.775069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.644053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-25T08:48:43.775203Z","caller":"traceutil/trace.go:171","msg":"trace[411061513] linearizableReadLoop","detail":"{readStateIndex:2451; appliedIndex:2450; }","duration":"240.225563ms","start":"2026-04-25T08:48:43.534952Z","end":"2026-04-25T08:48:43.775178Z","steps":["trace[411061513] 'read index received' (duration: 49.59µs)","trace[411061513] 'applied index is now lower than readState.Index' (duration: 240.174763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T08:48:43.775439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.461886ms","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-04-25T08:48:43.775473Z","caller":"traceutil/trace.go:171","msg":"trace[1730977338] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2352; }","duration":"240.552217ms","start":"2026-04-25T08:48:43.534907Z","end":"2026-04-25T08:48:43.775459Z","steps":["trace[1730977338] 'agreement among raft nodes before linearized reading' (duration: 240.359515ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:48:43.775513Z","caller":"traceutil/trace.go:171","msg":"trace[1132680388] transaction","detail":"{read_only:false; response_revision:2352; number_of_response:1; }","duration":"341.481706ms","start":"2026-04-25T08:48:43.433995Z","end":"2026-04-25T08:48:43.775476Z","steps":["trace[1132680388] 'process raft request' (duration: 29.299906ms)","trace[1132680388] 'compare' (duration: 311.474231ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T08:48:43.775658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T08:48:43.43397Z","time spent":"341.611177ms","remote":"127.0.0.1:42224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T08:48:54.97628Z","caller":"traceutil/trace.go:171","msg":"trace[1893287616] transaction","detail":"{read_only:false; response_revision:2430; number_of_response:1; }","duration":"102.452396ms","start":"2026-04-25T08:48:54.87381Z","end":"2026-04-25T08:48:54.976262Z","steps":["trace[1893287616] 'process raft request' (duration: 102.318904ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:49:02.718765Z","caller":"traceutil/trace.go:171","msg":"trace[22057618] linearizableReadLoop","detail":"{readStateIndex:2590; appliedIndex:2588; }","duration":"237.262914ms","start":"2026-04-25T08:49:02.481475Z","end":"2026-04-25T08:49:02.718737Z","steps":["trace[22057618] 'read index received' (duration: 60.513077ms)","trace[22057618] 'applied index is now lower than readState.Index' (duration: 176.748917ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T08:49:02.718822Z","caller":"traceutil/trace.go:171","msg":"trace[1480761533] transaction","detail":"{read_only:false; response_revision:2487; number_of_response:1; }","duration":"243.576767ms","start":"2026-04-25T08:49:02.475215Z","end":"2026-04-25T08:49:02.718791Z","steps":["trace[1480761533] 'process raft request' (duration: 184.197303ms)","trace[1480761533] 'compare' (duration: 59.196702ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T08:49:02.719203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.678399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-25T08:49:02.719334Z","caller":"traceutil/trace.go:171","msg":"trace[412483276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2487; }","duration":"237.862411ms","start":"2026-04-25T08:49:02.481456Z","end":"2026-04-25T08:49:02.719319Z","steps":["trace[412483276] 'agreement among raft nodes before linearized reading' (duration: 237.385165ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:49:03.356173Z","caller":"traceutil/trace.go:171","msg":"trace[2056036101] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"181.125732ms","start":"2026-04-25T08:49:03.175016Z","end":"2026-04-25T08:49:03.356141Z","steps":["trace[2056036101] 'process raft request' (duration: 126.824907ms)","trace[2056036101] 'compare' (duration: 53.9555ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T08:50:47.437723Z","caller":"traceutil/trace.go:171","msg":"trace[361487490] transaction","detail":"{read_only:false; response_revision:3156; number_of_response:1; }","duration":"143.19942ms","start":"2026-04-25T08:50:47.294499Z","end":"2026-04-25T08:50:47.437699Z","steps":["trace[361487490] 'process raft request' (duration: 143.01611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:50:58.153225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.129541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-25T08:50:58.153348Z","caller":"traceutil/trace.go:171","msg":"trace[691751727] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:3242; }","duration":"227.284849ms","start":"2026-04-25T08:50:57.926036Z","end":"2026-04-25T08:50:58.153321Z","steps":["trace[691751727] 'range keys from in-memory index tree' (duration: 226.876768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:50:58.153461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.087819ms","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-04-25T08:50:58.15352Z","caller":"traceutil/trace.go:171","msg":"trace[1813688687] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3243; }","duration":"204.155643ms","start":"2026-04-25T08:50:57.94935Z","end":"2026-04-25T08:50:58.153506Z","steps":["trace[1813688687] 'agreement among raft nodes before linearized reading' (duration: 203.962582ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:50:58.153259Z","caller":"traceutil/trace.go:171","msg":"trace[653276175] linearizableReadLoop","detail":"{readStateIndex:3371; appliedIndex:3370; }","duration":"203.880238ms","start":"2026-04-25T08:50:57.949354Z","end":"2026-04-25T08:50:58.153235Z","steps":["trace[653276175] 'read index received' (duration: 194.293224ms)","trace[653276175] 'applied index is now lower than readState.Index' (duration: 9.585974ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T08:50:58.154426Z","caller":"traceutil/trace.go:171","msg":"trace[747178698] transaction","detail":"{read_only:false; response_revision:3243; number_of_response:1; }","duration":"210.930576ms","start":"2026-04-25T08:50:57.943475Z","end":"2026-04-25T08:50:58.154405Z","steps":["trace[747178698] 'process raft request' (duration: 200.215584ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:50:58.17695Z","caller":"traceutil/trace.go:171","msg":"trace[489669353] transaction","detail":"{read_only:false; response_revision:3244; number_of_response:1; }","duration":"225.361064ms","start":"2026-04-25T08:50:57.951571Z","end":"2026-04-25T08:50:58.176932Z","steps":["trace[489669353] 'process raft request' (duration: 225.27015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:50:58.177092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.67255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T08:50:58.17716Z","caller":"traceutil/trace.go:171","msg":"trace[1676246416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3244; }","duration":"191.774686ms","start":"2026-04-25T08:50:57.985371Z","end":"2026-04-25T08:50:58.177146Z","steps":["trace[1676246416] 'agreement among raft nodes before linearized reading' (duration: 191.627859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T08:50:58.177388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.936132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-25T08:50:58.17741Z","caller":"traceutil/trace.go:171","msg":"trace[51781962] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:3244; }","duration":"211.978664ms","start":"2026-04-25T08:50:57.965424Z","end":"2026-04-25T08:50:58.177403Z","steps":["trace[51781962] 'agreement among raft nodes before linearized reading' (duration: 211.923051ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T08:55:04.56486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2832} {"level":"info","ts":"2026-04-25T08:55:04.633282Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2832,"took":"66.90752ms","hash":2127047555,"current-db-size-bytes":38449152,"current-db-size":"38 MB","current-db-size-in-use-bytes":14131200,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-04-25T08:55:04.633355Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2127047555,"revision":2832,"compact-revision":-1} {"level":"warn","ts":"2026-04-25T08:57:30.388017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.499172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8bc57cf1-99a9-426a-bb13-57c2a2aaec78\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-04-25T08:57:30.388111Z","caller":"traceutil/trace.go:171","msg":"trace[1593354652] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8bc57cf1-99a9-426a-bb13-57c2a2aaec78; range_end:; response_count:1; response_revision:6508; }","duration":"128.630933ms","start":"2026-04-25T08:57:30.259461Z","end":"2026-04-25T08:57:30.388092Z","steps":["trace[1593354652] 'range keys from in-memory index tree' (duration: 128.365189ms)"],"step_count":1}