{"level":"warn","ts":"2026-07-13T16:44:51.691713Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-13T16:44:51.691988Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-07-13T16:44:51.692154Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-13T16:44:51.692202Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-07-13T16:44:51.692236Z","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-07-13T16:44:51.694558Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-07-13T16:44:51.694844Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-07-13T16:44:51.69741Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.128254ms"} {"level":"info","ts":"2026-07-13T16:44:51.704914Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-07-13T16:44:51.705017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-07-13T16:44:51.705049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-07-13T16:44:51.705068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-13T16:44:51.705083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-07-13T16:44:51.705125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-07-13T16:44:51.710251Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-13T16:44:51.712997Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-13T16:44:51.714545Z","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-07-13T16:44:51.717416Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-13T16:44:51.717617Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-13T16:44:51.717834Z","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-07-13T16:44:51.717889Z","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-07-13T16:44:51.717915Z","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-07-13T16:44:51.718533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-07-13T16:44:51.718639Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-07-13T16:44:51.72392Z","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-07-13T16:44:51.724088Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-07-13T16:44:51.724129Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-07-13T16:44:51.72502Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-13T16:44:51.725113Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-13T16:44:52.505571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-07-13T16:44:52.50567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-13T16:44:52.505705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-07-13T16:44:52.505737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-07-13T16:44:52.505748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-07-13T16:44:52.505764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-07-13T16:44:52.505825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-07-13T16:44:52.507251Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-07-13T16:44:52.507302Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-13T16:44:52.507835Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T16:44:52.508574Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-13T16:44:52.509115Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T16:44:52.509256Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T16:44:52.509285Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T16:44:52.509762Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-13T16:44:52.509812Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-13T16:44:52.517823Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-13T16:44:52.519728Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-07-13T16:45:11.301761Z","caller":"traceutil/trace.go:171","msg":"trace[1269903843] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"215.872225ms","start":"2026-07-13T16:45:11.085842Z","end":"2026-07-13T16:45:11.301714Z","steps":["trace[1269903843] 'process raft request' (duration: 215.627419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:45:33.514644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.600062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7tw2j\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-13T16:45:33.514807Z","caller":"traceutil/trace.go:171","msg":"trace[485995908] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7tw2j; range_end:; response_count:1; response_revision:617; }","duration":"151.830298ms","start":"2026-07-13T16:45:33.362937Z","end":"2026-07-13T16:45:33.514767Z","steps":["trace[485995908] 'range keys from in-memory index tree' (duration: 151.346165ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:45:34.067399Z","caller":"traceutil/trace.go:171","msg":"trace[1430090322] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"351.917854ms","start":"2026-07-13T16:45:33.715455Z","end":"2026-07-13T16:45:34.067373Z","steps":["trace[1430090322] 'read index received' (duration: 349.762ms)","trace[1430090322] 'applied index is now lower than readState.Index' (duration: 2.154764ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T16:45:34.067517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.044667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:45:34.067538Z","caller":"traceutil/trace.go:171","msg":"trace[195229192] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"425.958255ms","start":"2026-07-13T16:45:33.64155Z","end":"2026-07-13T16:45:34.067508Z","steps":["trace[195229192] 'process raft request' (duration: 424.420685ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:45:34.067615Z","caller":"traceutil/trace.go:171","msg":"trace[372892540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"352.204381ms","start":"2026-07-13T16:45:33.715399Z","end":"2026-07-13T16:45:34.067604Z","steps":["trace[372892540] 'agreement among raft nodes before linearized reading' (duration: 352.063608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:45:34.067692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:45:33.715384Z","time spent":"352.297654ms","remote":"127.0.0.1:58574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-13T16:45:34.068144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:45:33.641527Z","time spent":"426.100359ms","remote":"127.0.0.1:58678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T16:45:34.770263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.867947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-13T16:45:34.770592Z","caller":"traceutil/trace.go:171","msg":"trace[522318195] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"108.978415ms","start":"2026-07-13T16:45:34.661589Z","end":"2026-07-13T16:45:34.770567Z","steps":["trace[522318195] 'process raft request' (duration: 108.816981ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:45:34.770577Z","caller":"traceutil/trace.go:171","msg":"trace[1212847142] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"622.698807ms","start":"2026-07-13T16:45:34.147822Z","end":"2026-07-13T16:45:34.770521Z","steps":["trace[1212847142] 'read index received' (duration: 330.292606ms)","trace[1212847142] 'applied index is now lower than readState.Index' (duration: 292.401491ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T16:45:34.770817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.980854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-13T16:45:34.770861Z","caller":"traceutil/trace.go:171","msg":"trace[953121394] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"623.079197ms","start":"2026-07-13T16:45:34.147771Z","end":"2026-07-13T16:45:34.77085Z","steps":["trace[953121394] 'agreement among raft nodes before linearized reading' (duration: 622.871672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:45:34.770894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:45:34.147744Z","time spent":"623.143078ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-13T16:45:34.770943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.161427ms","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-07-13T16:45:34.77055Z","caller":"traceutil/trace.go:171","msg":"trace[817998983] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"651.036904ms","start":"2026-07-13T16:45:34.119383Z","end":"2026-07-13T16:45:34.77042Z","steps":["trace[817998983] 'process raft request' (duration: 358.715486ms)","trace[817998983] 'compare' (duration: 291.671372ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:45:34.771002Z","caller":"traceutil/trace.go:171","msg":"trace[1637746576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"306.2695ms","start":"2026-07-13T16:45:34.464717Z","end":"2026-07-13T16:45:34.770987Z","steps":["trace[1637746576] 'agreement among raft nodes before linearized reading' (duration: 306.150877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:45:34.771206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:45:34.464698Z","time spent":"306.490785ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-13T16:45:34.771317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:45:34.119363Z","time spent":"651.69365ms","remote":"127.0.0.1:58802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-13T16:45:51.652042Z","caller":"traceutil/trace.go:171","msg":"trace[678221662] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"177.155657ms","start":"2026-07-13T16:45:51.474855Z","end":"2026-07-13T16:45:51.652011Z","steps":["trace[678221662] 'process raft request' (duration: 176.87318ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:46:19.126186Z","caller":"traceutil/trace.go:171","msg":"trace[161822932] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"144.321182ms","start":"2026-07-13T16:46:18.981839Z","end":"2026-07-13T16:46:19.12616Z","steps":["trace[161822932] 'process raft request' (duration: 144.150227ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:46:19.126785Z","caller":"traceutil/trace.go:171","msg":"trace[1086122941] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"143.953774ms","start":"2026-07-13T16:46:18.98281Z","end":"2026-07-13T16:46:19.126764Z","steps":["trace[1086122941] 'process raft request' (duration: 143.840602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:04.591256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.28686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c812a21-0f2a-4191-830a-e954d8ca0823\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-13T16:47:04.59137Z","caller":"traceutil/trace.go:171","msg":"trace[293850847] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1c812a21-0f2a-4191-830a-e954d8ca0823; range_end:; response_count:1; response_revision:1850; }","duration":"154.467604ms","start":"2026-07-13T16:47:04.436881Z","end":"2026-07-13T16:47:04.591348Z","steps":["trace[293850847] 'range keys from in-memory index tree' (duration: 154.051784ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:04.912584Z","caller":"traceutil/trace.go:171","msg":"trace[211189592] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1922; }","duration":"196.723784ms","start":"2026-07-13T16:47:04.715831Z","end":"2026-07-13T16:47:04.912555Z","steps":["trace[211189592] 'read index received' (duration: 196.629311ms)","trace[211189592] 'applied index is now lower than readState.Index' (duration: 93.432µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:47:04.91267Z","caller":"traceutil/trace.go:171","msg":"trace[1232477470] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"258.534609ms","start":"2026-07-13T16:47:04.654104Z","end":"2026-07-13T16:47:04.912638Z","steps":["trace[1232477470] 'process raft request' (duration: 258.275672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:04.912744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.891437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:47:04.912817Z","caller":"traceutil/trace.go:171","msg":"trace[1014156495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1851; }","duration":"197.01717ms","start":"2026-07-13T16:47:04.71578Z","end":"2026-07-13T16:47:04.912797Z","steps":["trace[1014156495] 'agreement among raft nodes before linearized reading' (duration: 196.882777ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:04.919857Z","caller":"traceutil/trace.go:171","msg":"trace[2127675336] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"105.229791ms","start":"2026-07-13T16:47:04.814605Z","end":"2026-07-13T16:47:04.919835Z","steps":["trace[2127675336] 'process raft request' (duration: 105.106568ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:08.610741Z","caller":"traceutil/trace.go:171","msg":"trace[97468701] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1964; }","duration":"134.238072ms","start":"2026-07-13T16:47:08.476475Z","end":"2026-07-13T16:47:08.610713Z","steps":["trace[97468701] 'read index received' (duration: 134.127759ms)","trace[97468701] 'applied index is now lower than readState.Index' (duration: 109.133µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:47:08.61095Z","caller":"traceutil/trace.go:171","msg":"trace[1540906057] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"194.489118ms","start":"2026-07-13T16:47:08.416414Z","end":"2026-07-13T16:47:08.610903Z","steps":["trace[1540906057] 'process raft request' (duration: 194.114979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:08.611104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.609341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-13T16:47:08.611192Z","caller":"traceutil/trace.go:171","msg":"trace[1595859001] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1891; }","duration":"134.751144ms","start":"2026-07-13T16:47:08.476423Z","end":"2026-07-13T16:47:08.611174Z","steps":["trace[1595859001] 'agreement among raft nodes before linearized reading' (duration: 134.396396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:13.075727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.348275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:47:13.075831Z","caller":"traceutil/trace.go:171","msg":"trace[218491162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1925; }","duration":"360.499029ms","start":"2026-07-13T16:47:12.715306Z","end":"2026-07-13T16:47:13.075805Z","steps":["trace[218491162] 'range keys from in-memory index tree' (duration: 360.274533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:13.075885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:47:12.715287Z","time spent":"360.585911ms","remote":"127.0.0.1:58570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-13T16:47:13.205136Z","caller":"traceutil/trace.go:171","msg":"trace[1521665869] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"245.020512ms","start":"2026-07-13T16:47:12.960094Z","end":"2026-07-13T16:47:13.205115Z","steps":["trace[1521665869] 'process raft request' (duration: 244.879919ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:13.213421Z","caller":"traceutil/trace.go:171","msg":"trace[1655340561] linearizableReadLoop","detail":"{readStateIndex:2002; appliedIndex:2001; }","duration":"136.292693ms","start":"2026-07-13T16:47:13.077113Z","end":"2026-07-13T16:47:13.213406Z","steps":["trace[1655340561] 'read index received' (duration: 128.339845ms)","trace[1655340561] 'applied index is now lower than readState.Index' (duration: 7.952178ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:47:13.21344Z","caller":"traceutil/trace.go:171","msg":"trace[1248750846] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"253.179275ms","start":"2026-07-13T16:47:12.960246Z","end":"2026-07-13T16:47:13.213425Z","steps":["trace[1248750846] 'process raft request' (duration: 253.012271ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:13.213512Z","caller":"traceutil/trace.go:171","msg":"trace[1262593630] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"119.19642ms","start":"2026-07-13T16:47:13.094307Z","end":"2026-07-13T16:47:13.213504Z","steps":["trace[1262593630] 'process raft request' (duration: 119.068557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:13.213619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.498648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:47:13.21364Z","caller":"traceutil/trace.go:171","msg":"trace[1753559603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1928; }","duration":"136.55658ms","start":"2026-07-13T16:47:13.077075Z","end":"2026-07-13T16:47:13.213632Z","steps":["trace[1753559603] 'agreement among raft nodes before linearized reading' (duration: 136.507779ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:13.213758Z","caller":"traceutil/trace.go:171","msg":"trace[1685215442] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1928; }","duration":"100.010103ms","start":"2026-07-13T16:47:13.113714Z","end":"2026-07-13T16:47:13.213724Z","steps":["trace[1685215442] 'agreement among raft nodes before linearized reading' (duration: 99.861389ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:16.855655Z","caller":"traceutil/trace.go:171","msg":"trace[1591908367] linearizableReadLoop","detail":"{readStateIndex:2027; appliedIndex:2026; }","duration":"140.17737ms","start":"2026-07-13T16:47:16.715454Z","end":"2026-07-13T16:47:16.855632Z","steps":["trace[1591908367] 'read index received' (duration: 140.006465ms)","trace[1591908367] 'applied index is now lower than readState.Index' (duration: 169.655µs)"],"step_count":2} {"level":"warn","ts":"2026-07-13T16:47:16.8558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.337883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:47:16.855789Z","caller":"traceutil/trace.go:171","msg":"trace[1083218738] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"499.750134ms","start":"2026-07-13T16:47:16.356006Z","end":"2026-07-13T16:47:16.855756Z","steps":["trace[1083218738] 'process raft request' (duration: 499.448346ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:16.855828Z","caller":"traceutil/trace.go:171","msg":"trace[1750052016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1951; }","duration":"140.386644ms","start":"2026-07-13T16:47:16.715433Z","end":"2026-07-13T16:47:16.855819Z","steps":["trace[1750052016] 'agreement among raft nodes before linearized reading' (duration: 140.291632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:16.855956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:47:16.355989Z","time spent":"499.874067ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:47:49.450137Z","caller":"traceutil/trace.go:171","msg":"trace[1853206713] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"113.791758ms","start":"2026-07-13T16:47:49.336306Z","end":"2026-07-13T16:47:49.450098Z","steps":["trace[1853206713] 'process raft request' (duration: 113.577833ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:49.586966Z","caller":"traceutil/trace.go:171","msg":"trace[873065102] linearizableReadLoop","detail":"{readStateIndex:2226; appliedIndex:2224; }","duration":"127.922668ms","start":"2026-07-13T16:47:49.459028Z","end":"2026-07-13T16:47:49.586951Z","steps":["trace[873065102] 'read index received' (duration: 65.133848ms)","trace[873065102] 'applied index is now lower than readState.Index' (duration: 62.78758ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:47:49.586843Z","caller":"traceutil/trace.go:171","msg":"trace[1092891655] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"248.402282ms","start":"2026-07-13T16:47:49.338418Z","end":"2026-07-13T16:47:49.586821Z","steps":["trace[1092891655] 'process raft request' (duration: 185.728614ms)","trace[1092891655] 'compare' (duration: 62.527864ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T16:47:49.587393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.337308ms","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-07-13T16:47:49.587498Z","caller":"traceutil/trace.go:171","msg":"trace[1487795331] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2144; }","duration":"128.488132ms","start":"2026-07-13T16:47:49.458986Z","end":"2026-07-13T16:47:49.587474Z","steps":["trace[1487795331] 'agreement among raft nodes before linearized reading' (duration: 128.131303ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:49.587315Z","caller":"traceutil/trace.go:171","msg":"trace[828065961] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"237.45469ms","start":"2026-07-13T16:47:49.349804Z","end":"2026-07-13T16:47:49.587258Z","steps":["trace[828065961] 'process raft request' (duration: 236.982658ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:55.713721Z","caller":"traceutil/trace.go:171","msg":"trace[572494416] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"237.893251ms","start":"2026-07-13T16:47:55.475796Z","end":"2026-07-13T16:47:55.713689Z","steps":["trace[572494416] 'process raft request' (duration: 237.392078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:56.188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.173377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T16:47:56.188336Z","caller":"traceutil/trace.go:171","msg":"trace[1828134258] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"672.626501ms","start":"2026-07-13T16:47:55.515678Z","end":"2026-07-13T16:47:56.188304Z","steps":["trace[1828134258] 'process raft request' (duration: 672.442947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:56.188511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:47:55.515665Z","time spent":"672.765474ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:47:56.18856Z","caller":"traceutil/trace.go:171","msg":"trace[1255073836] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"572.023952ms","start":"2026-07-13T16:47:55.616517Z","end":"2026-07-13T16:47:56.188541Z","steps":["trace[1255073836] 'process raft request' (duration: 571.699644ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:56.18871Z","caller":"traceutil/trace.go:171","msg":"trace[1173153743] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"169.294055ms","start":"2026-07-13T16:47:56.019009Z","end":"2026-07-13T16:47:56.188304Z","steps":["trace[1173153743] 'process raft request' (duration: 169.250954ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:56.188694Z","caller":"traceutil/trace.go:171","msg":"trace[612721555] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"575.563079ms","start":"2026-07-13T16:47:55.613068Z","end":"2026-07-13T16:47:56.188631Z","steps":["trace[612721555] 'process raft request' (duration: 575.113318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:56.188791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:47:55.61649Z","time spent":"572.152235ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T16:47:56.188912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:47:55.613048Z","time spent":"575.763466ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T16:47:56.189188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.589582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:47:56.188286Z","caller":"traceutil/trace.go:171","msg":"trace[210979557] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"700.243057ms","start":"2026-07-13T16:47:55.487972Z","end":"2026-07-13T16:47:56.188215Z","steps":["trace[210979557] 'process raft request' (duration: 393.770553ms)","trace[210979557] 'compare' (duration: 306.015533ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:47:56.189244Z","caller":"traceutil/trace.go:171","msg":"trace[1872093244] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2177; }","duration":"577.690334ms","start":"2026-07-13T16:47:55.611541Z","end":"2026-07-13T16:47:56.189231Z","steps":["trace[1872093244] 'agreement among raft nodes before linearized reading' (duration: 577.591202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:56.189282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:47:55.611519Z","time spent":"577.754285ms","remote":"127.0.0.1:59812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-13T16:47:56.189339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:47:55.487953Z","time spent":"701.334095ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:47:56.189113Z","caller":"traceutil/trace.go:171","msg":"trace[1668818692] linearizableReadLoop","detail":"{readStateIndex:2258; appliedIndex:2255; }","duration":"577.464988ms","start":"2026-07-13T16:47:55.611602Z","end":"2026-07-13T16:47:56.189067Z","steps":["trace[1668818692] 'read index received' (duration: 101.603305ms)","trace[1668818692] 'applied index is now lower than readState.Index' (duration: 475.859743ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T16:47:56.18975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.735609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-13T16:47:56.189755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.4196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-13T16:47:56.189801Z","caller":"traceutil/trace.go:171","msg":"trace[1456049417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2177; }","duration":"545.805691ms","start":"2026-07-13T16:47:55.643983Z","end":"2026-07-13T16:47:56.189789Z","steps":["trace[1456049417] 'agreement among raft nodes before linearized reading' (duration: 545.621756ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:47:56.189806Z","caller":"traceutil/trace.go:171","msg":"trace[629259799] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2177; }","duration":"454.517333ms","start":"2026-07-13T16:47:55.735276Z","end":"2026-07-13T16:47:56.189793Z","steps":["trace[629259799] 'agreement among raft nodes before linearized reading' (duration: 454.427501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:56.189808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.505086ms","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":"warn","ts":"2026-07-13T16:47:56.189834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:47:55.643973Z","time spent":"545.853952ms","remote":"127.0.0.1:58838","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-07-13T16:47:56.189841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:47:55.735255Z","time spent":"454.577704ms","remote":"127.0.0.1:56624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":28,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"info","ts":"2026-07-13T16:47:56.189852Z","caller":"traceutil/trace.go:171","msg":"trace[983239106] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2177; }","duration":"558.61684ms","start":"2026-07-13T16:47:55.63122Z","end":"2026-07-13T16:47:56.189837Z","steps":["trace[983239106] 'agreement among raft nodes before linearized reading' (duration: 558.372034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:56.189834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.709666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-13T16:47:56.189887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:47:55.631207Z","time spent":"558.671651ms","remote":"127.0.0.1:54872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5433,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-13T16:47:56.189912Z","caller":"traceutil/trace.go:171","msg":"trace[1767858349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2177; }","duration":"557.818509ms","start":"2026-07-13T16:47:55.632071Z","end":"2026-07-13T16:47:56.18989Z","steps":["trace[1767858349] 'agreement among raft nodes before linearized reading' (duration: 557.565593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:47:56.189953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:47:55.63206Z","time spent":"557.88539ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-13T16:47:56.353536Z","caller":"traceutil/trace.go:171","msg":"trace[542564095] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"159.708798ms","start":"2026-07-13T16:47:56.193795Z","end":"2026-07-13T16:47:56.353504Z","steps":["trace[542564095] 'process raft request' (duration: 134.949332ms)","trace[542564095] 'compare' (duration: 24.584011ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:49:03.046126Z","caller":"traceutil/trace.go:171","msg":"trace[1518124683] transaction","detail":"{read_only:false; response_revision:2548; number_of_response:1; }","duration":"375.057054ms","start":"2026-07-13T16:49:02.671039Z","end":"2026-07-13T16:49:03.046096Z","steps":["trace[1518124683] 'process raft request' (duration: 374.163142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:49:03.046249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:02.671015Z","time spent":"375.174626ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:49:03.04702Z","caller":"traceutil/trace.go:171","msg":"trace[78601394] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"375.849003ms","start":"2026-07-13T16:49:02.671139Z","end":"2026-07-13T16:49:03.046988Z","steps":["trace[78601394] 'process raft request' (duration: 375.618467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:49:03.047173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:02.671117Z","time spent":"375.977326ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T16:49:07.33823Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882259517935942,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-13T16:49:07.810948Z","caller":"traceutil/trace.go:171","msg":"trace[423266593] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"975.729003ms","start":"2026-07-13T16:49:06.835188Z","end":"2026-07-13T16:49:07.810917Z","steps":["trace[423266593] 'process raft request' (duration: 975.536218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:49:07.811134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:06.835167Z","time spent":"975.869937ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:49:07.81186Z","caller":"traceutil/trace.go:171","msg":"trace[105771841] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"974.269418ms","start":"2026-07-13T16:49:06.837564Z","end":"2026-07-13T16:49:07.811833Z","steps":["trace[105771841] 'process raft request' (duration: 974.130284ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:49:07.811927Z","caller":"traceutil/trace.go:171","msg":"trace[2010523170] linearizableReadLoop","detail":"{readStateIndex:2666; appliedIndex:2663; }","duration":"974.113543ms","start":"2026-07-13T16:49:06.837793Z","end":"2026-07-13T16:49:07.811907Z","steps":["trace[2010523170] 'read index received' (duration: 972.933134ms)","trace[2010523170] 'applied index is now lower than readState.Index' (duration: 1.179309ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T16:49:07.812027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:06.837542Z","time spent":"974.409751ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T16:49:07.812082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.285678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:49:07.812152Z","caller":"traceutil/trace.go:171","msg":"trace[796632340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2567; }","duration":"974.350229ms","start":"2026-07-13T16:49:06.837756Z","end":"2026-07-13T16:49:07.812106Z","steps":["trace[796632340] 'agreement among raft nodes before linearized reading' (duration: 974.251017ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:49:07.812108Z","caller":"traceutil/trace.go:171","msg":"trace[470732301] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"973.887619ms","start":"2026-07-13T16:49:06.838146Z","end":"2026-07-13T16:49:07.812034Z","steps":["trace[470732301] 'process raft request' (duration: 973.645813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:49:07.812294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.223381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-node-lease/\" range_end:\"/registry/deployments/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T16:49:07.812368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.668198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/ingress-nginx/kube-root-ca.crt\" ","response":"range_response_count:1 size:1750"} {"level":"warn","ts":"2026-07-13T16:49:07.812388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.034242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/kube-public/\" range_end:\"/registry/cronjobs/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:49:07.812395Z","caller":"traceutil/trace.go:171","msg":"trace[856195458] range","detail":"{range_begin:/registry/deployments/kube-node-lease/; range_end:/registry/deployments/kube-node-lease0; response_count:0; response_revision:2567; }","duration":"894.409866ms","start":"2026-07-13T16:49:06.917964Z","end":"2026-07-13T16:49:07.812373Z","steps":["trace[856195458] 'agreement among raft nodes before linearized reading' (duration: 894.18834ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:49:07.812406Z","caller":"traceutil/trace.go:171","msg":"trace[845968339] range","detail":"{range_begin:/registry/configmaps/ingress-nginx/kube-root-ca.crt; range_end:; response_count:1; response_revision:2567; }","duration":"941.72351ms","start":"2026-07-13T16:49:06.870671Z","end":"2026-07-13T16:49:07.812395Z","steps":["trace[845968339] 'agreement among raft nodes before linearized reading' (duration: 941.592367ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:49:07.812433Z","caller":"traceutil/trace.go:171","msg":"trace[1873783060] range","detail":"{range_begin:/registry/cronjobs/kube-public/; range_end:/registry/cronjobs/kube-public0; response_count:0; response_revision:2567; }","duration":"972.082653ms","start":"2026-07-13T16:49:06.840331Z","end":"2026-07-13T16:49:07.812414Z","steps":["trace[1873783060] 'agreement among raft nodes before linearized reading' (duration: 972.036062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:49:07.812454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:06.870659Z","time spent":"941.779671ms","remote":"127.0.0.1:58688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":1771,"request content":"key:\"/registry/configmaps/ingress-nginx/kube-root-ca.crt\" "} {"level":"warn","ts":"2026-07-13T16:49:07.812459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:06.917945Z","time spent":"894.496548ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":26,"request content":"key:\"/registry/deployments/kube-node-lease/\" range_end:\"/registry/deployments/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-07-13T16:49:07.812467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:06.838138Z","time spent":"974.239337ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T16:49:07.81248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.672786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-13T16:49:07.812557Z","caller":"traceutil/trace.go:171","msg":"trace[2003521405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2567; }","duration":"753.788688ms","start":"2026-07-13T16:49:07.058751Z","end":"2026-07-13T16:49:07.81254Z","steps":["trace[2003521405] 'agreement among raft nodes before linearized reading' (duration: 753.553902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:49:07.812348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.602368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5641"} {"level":"warn","ts":"2026-07-13T16:49:07.812188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:06.837741Z","time spent":"974.438072ms","remote":"127.0.0.1:58570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-13T16:49:07.812598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:07.05874Z","time spent":"753.84882ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-13T16:49:07.812609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.078231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-13T16:49:07.81268Z","caller":"traceutil/trace.go:171","msg":"trace[986070526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2567; }","duration":"755.195624ms","start":"2026-07-13T16:49:07.057472Z","end":"2026-07-13T16:49:07.812668Z","steps":["trace[986070526] 'agreement among raft nodes before linearized reading' (duration: 755.02598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:49:07.812681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.322612ms","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-07-13T16:49:07.812717Z","caller":"traceutil/trace.go:171","msg":"trace[327856944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2567; }","duration":"338.395954ms","start":"2026-07-13T16:49:07.474312Z","end":"2026-07-13T16:49:07.812708Z","steps":["trace[327856944] 'agreement among raft nodes before linearized reading' (duration: 338.307902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:49:07.812742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:07.474291Z","time spent":"338.445436ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-13T16:49:07.812741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:07.057453Z","time spent":"755.251555ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":525,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-13T16:49:07.812735Z","caller":"traceutil/trace.go:171","msg":"trace[963293032] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:2567; }","duration":"910.938486ms","start":"2026-07-13T16:49:06.901717Z","end":"2026-07-13T16:49:07.812656Z","steps":["trace[963293032] 'agreement among raft nodes before linearized reading' (duration: 910.538096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:49:07.812199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.172491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-13T16:49:07.812848Z","caller":"traceutil/trace.go:171","msg":"trace[2138549738] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2567; }","duration":"973.869138ms","start":"2026-07-13T16:49:06.838969Z","end":"2026-07-13T16:49:07.812838Z","steps":["trace[2138549738] 'agreement among raft nodes before linearized reading' (duration: 973.13941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:49:07.812881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:06.838959Z","time spent":"973.912159ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":11,"response size":28,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-07-13T16:49:07.812943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:06.901705Z","time spent":"911.157922ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":5662,"request content":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" "} {"level":"warn","ts":"2026-07-13T16:49:07.813005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:49:06.84032Z","time spent":"972.661328ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":26,"request content":"key:\"/registry/cronjobs/kube-public/\" range_end:\"/registry/cronjobs/kube-public0\" limit:500 "}