{"level":"warn","ts":"2026-04-20T11:48:55.403053Z","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-20T11:48:55.403124Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-20T11:48:55.403186Z","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-20T11:48:55.403232Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-20T11:48:55.403268Z","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-20T11:48:55.403958Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-20T11:48:55.404059Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-20T11:48:55.40863Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.113676ms"} {"level":"info","ts":"2026-04-20T11:48:55.414224Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-20T11:48:55.414383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T11:48:55.414413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-20T11:48:55.414492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T11:48:55.414599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-20T11:48:55.414709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-20T11:48:55.421414Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T11:48:55.423619Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T11:48:55.425123Z","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-20T11:48:55.427738Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T11:48:55.427954Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T11:48:55.427966Z","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-20T11:48:55.428078Z","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-20T11:48:55.428099Z","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-20T11:48:55.428703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-20T11:48:55.428905Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-20T11:48:55.43183Z","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-20T11:48:55.431999Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T11:48:55.432022Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T11:48:55.432066Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-20T11:48:55.432075Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-20T11:48:55.915414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T11:48:55.915505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T11:48:55.915541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-20T11:48:55.915563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-20T11:48:55.915574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-20T11:48:55.915602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-20T11:48:55.915635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-20T11:48:55.916652Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:48:55.917572Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T11:48:55.917729Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:48:55.917777Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:48:55.918043Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T11:48:55.918117Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T11:48:55.91818Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:48:55.918394Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:48:55.918441Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:48:55.91947Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T11:48:55.921073Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-04-20T11:49:22.760939Z","caller":"traceutil/trace.go:171","msg":"trace[5652925] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"101.76522ms","start":"2026-04-20T11:49:22.659143Z","end":"2026-04-20T11:49:22.760908Z","steps":["trace[5652925] 'process raft request' (duration: 95.473965ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:49:22.761224Z","caller":"traceutil/trace.go:171","msg":"trace[1511420123] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"102.069993ms","start":"2026-04-20T11:49:22.65912Z","end":"2026-04-20T11:49:22.76119Z","steps":["trace[1511420123] 'process raft request' (duration: 101.681169ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:49:22.761467Z","caller":"traceutil/trace.go:171","msg":"trace[709767546] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"102.195794ms","start":"2026-04-20T11:49:22.659249Z","end":"2026-04-20T11:49:22.761445Z","steps":["trace[709767546] 'process raft request' (duration: 101.809761ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:49:22.761562Z","caller":"traceutil/trace.go:171","msg":"trace[1597367157] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"101.611668ms","start":"2026-04-20T11:49:22.65994Z","end":"2026-04-20T11:49:22.761551Z","steps":["trace[1597367157] 'process raft request' (duration: 101.364316ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:49:25.418762Z","caller":"traceutil/trace.go:171","msg":"trace[1232598050] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"113.233947ms","start":"2026-04-20T11:49:25.305497Z","end":"2026-04-20T11:49:25.418731Z","steps":["trace[1232598050] 'process raft request' (duration: 60.168964ms)","trace[1232598050] 'compare' (duration: 52.84096ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:49:25.5494Z","caller":"traceutil/trace.go:171","msg":"trace[448721844] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"120.316327ms","start":"2026-04-20T11:49:25.429061Z","end":"2026-04-20T11:49:25.549377Z","steps":["trace[448721844] 'read index received' (duration: 99.61844ms)","trace[448721844] 'applied index is now lower than readState.Index' (duration: 20.696877ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:49:25.550005Z","caller":"traceutil/trace.go:171","msg":"trace[1603007450] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"164.400323ms","start":"2026-04-20T11:49:25.385552Z","end":"2026-04-20T11:49:25.549952Z","steps":["trace[1603007450] 'process raft request' (duration: 143.824447ms)","trace[1603007450] 'compare' (duration: 19.556407ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:49:25.553022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.462198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:49:25.553115Z","caller":"traceutil/trace.go:171","msg":"trace[1027034861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"124.06979ms","start":"2026-04-20T11:49:25.429028Z","end":"2026-04-20T11:49:25.553097Z","steps":["trace[1027034861] 'agreement among raft nodes before linearized reading' (duration: 120.435688ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:49:30.634819Z","caller":"traceutil/trace.go:171","msg":"trace[1374369704] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"469.017045ms","start":"2026-04-20T11:49:30.165776Z","end":"2026-04-20T11:49:30.634793Z","steps":["trace[1374369704] 'read index received' (duration: 468.291658ms)","trace[1374369704] 'applied index is now lower than readState.Index' (duration: 724.347µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:49:30.63489Z","caller":"traceutil/trace.go:171","msg":"trace[2068022568] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"565.660516ms","start":"2026-04-20T11:49:30.069174Z","end":"2026-04-20T11:49:30.634835Z","steps":["trace[2068022568] 'process raft request' (duration: 565.05305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:49:30.635092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.768679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:49:30.635157Z","caller":"traceutil/trace.go:171","msg":"trace[898004294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"203.902689ms","start":"2026-04-20T11:49:30.431237Z","end":"2026-04-20T11:49:30.635139Z","steps":["trace[898004294] 'agreement among raft nodes before linearized reading' (duration: 203.774088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:49:30.635148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.014731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T11:49:30.635214Z","caller":"traceutil/trace.go:171","msg":"trace[2036139095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"104.116762ms","start":"2026-04-20T11:49:30.53108Z","end":"2026-04-20T11:49:30.635197Z","steps":["trace[2036139095] 'agreement among raft nodes before linearized reading' (duration: 103.988991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:49:30.635537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.6432ms","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-20T11:49:30.635648Z","caller":"traceutil/trace.go:171","msg":"trace[1051848594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"469.878951ms","start":"2026-04-20T11:49:30.165746Z","end":"2026-04-20T11:49:30.635625Z","steps":["trace[1051848594] 'agreement among raft nodes before linearized reading' (duration: 469.209456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:49:30.635718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:49:30.165733Z","time spent":"469.973992ms","remote":"127.0.0.1:52412","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":"warn","ts":"2026-04-20T11:49:30.637541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:49:30.069147Z","time spent":"565.849627ms","remote":"127.0.0.1:52242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:49:31.141327Z","caller":"traceutil/trace.go:171","msg":"trace[753723658] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"442.031665ms","start":"2026-04-20T11:49:30.699271Z","end":"2026-04-20T11:49:31.141303Z","steps":["trace[753723658] 'process raft request' (duration: 441.892243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:49:31.141459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:49:30.699247Z","time spent":"442.153835ms","remote":"127.0.0.1:52524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T11:49:31.45177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.068087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:49:31.451839Z","caller":"traceutil/trace.go:171","msg":"trace[2035755015] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:572; }","duration":"298.179847ms","start":"2026-04-20T11:49:31.153644Z","end":"2026-04-20T11:49:31.451824Z","steps":["trace[2035755015] 'range keys from in-memory index tree' (duration: 297.980706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:49:45.997128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.35339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T11:49:45.997818Z","caller":"traceutil/trace.go:171","msg":"trace[1522837177] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:728; }","duration":"126.115406ms","start":"2026-04-20T11:49:45.871674Z","end":"2026-04-20T11:49:45.99779Z","steps":["trace[1522837177] 'range keys from in-memory index tree' (duration: 125.166018ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:49:46.173184Z","caller":"traceutil/trace.go:171","msg":"trace[601496158] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"172.671298ms","start":"2026-04-20T11:49:46.000488Z","end":"2026-04-20T11:49:46.173159Z","steps":["trace[601496158] 'process raft request' (duration: 172.507807ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:49:46.186444Z","caller":"traceutil/trace.go:171","msg":"trace[20971910] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"184.955175ms","start":"2026-04-20T11:49:46.001463Z","end":"2026-04-20T11:49:46.186418Z","steps":["trace[20971910] 'process raft request' (duration: 184.761554ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:50:38.635319Z","caller":"traceutil/trace.go:171","msg":"trace[1788412527] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"128.424019ms","start":"2026-04-20T11:50:38.506864Z","end":"2026-04-20T11:50:38.635288Z","steps":["trace[1788412527] 'process raft request' (duration: 128.233321ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:50:38.718307Z","caller":"traceutil/trace.go:171","msg":"trace[1032356121] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"210.438813ms","start":"2026-04-20T11:50:38.507833Z","end":"2026-04-20T11:50:38.718272Z","steps":["trace[1032356121] 'process raft request' (duration: 163.705177ms)","trace[1032356121] 'compare' (duration: 46.608302ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:51:04.156116Z","caller":"traceutil/trace.go:171","msg":"trace[1626487499] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"448.309068ms","start":"2026-04-20T11:51:03.70775Z","end":"2026-04-20T11:51:04.156059Z","steps":["trace[1626487499] 'process raft request' (duration: 439.326138ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:51:04.1562Z","caller":"traceutil/trace.go:171","msg":"trace[397808393] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"126.249355ms","start":"2026-04-20T11:51:04.029931Z","end":"2026-04-20T11:51:04.15618Z","steps":["trace[397808393] 'process raft request' (duration: 126.212854ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:51:04.156505Z","caller":"traceutil/trace.go:171","msg":"trace[2010973744] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"361.158875ms","start":"2026-04-20T11:51:03.795333Z","end":"2026-04-20T11:51:04.156492Z","steps":["trace[2010973744] 'process raft request' (duration: 360.740203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:04.156615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:51:03.795314Z","time spent":"361.217126ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:51:04.156598Z","caller":"traceutil/trace.go:171","msg":"trace[1598838151] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1888; }","duration":"403.986785ms","start":"2026-04-20T11:51:03.752579Z","end":"2026-04-20T11:51:04.156565Z","steps":["trace[1598838151] 'read index received' (duration: 394.418959ms)","trace[1598838151] 'applied index is now lower than readState.Index' (duration: 9.565556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:51:04.156839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:51:03.707724Z","time spent":"448.517504ms","remote":"127.0.0.1:52328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7742,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:51:04.156897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.279289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b66b758-2d6e-440c-abb9-14599f17302e\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T11:51:04.156944Z","caller":"traceutil/trace.go:171","msg":"trace[1073708084] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3b66b758-2d6e-440c-abb9-14599f17302e; range_end:; response_count:1; response_revision:1818; }","duration":"345.374741ms","start":"2026-04-20T11:51:03.811541Z","end":"2026-04-20T11:51:04.156916Z","steps":["trace[1073708084] 'agreement among raft nodes before linearized reading' (duration: 345.256398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:04.156973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:51:03.811521Z","time spent":"345.445704ms","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b66b758-2d6e-440c-abb9-14599f17302e\" "} {"level":"warn","ts":"2026-04-20T11:51:04.15754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.750216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:51:04.157605Z","caller":"traceutil/trace.go:171","msg":"trace[1923565922] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1818; }","duration":"405.075115ms","start":"2026-04-20T11:51:03.752513Z","end":"2026-04-20T11:51:04.157588Z","steps":["trace[1923565922] 'agreement among raft nodes before linearized reading' (duration: 404.226552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:04.157651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:51:03.752489Z","time spent":"405.149397ms","remote":"127.0.0.1:51082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-20T11:51:04.763448Z","caller":"traceutil/trace.go:171","msg":"trace[2131469734] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"211.986455ms","start":"2026-04-20T11:51:04.551436Z","end":"2026-04-20T11:51:04.763423Z","steps":["trace[2131469734] 'process raft request' (duration: 211.80673ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:51:19.456144Z","caller":"traceutil/trace.go:171","msg":"trace[995512227] linearizableReadLoop","detail":"{readStateIndex:2022; appliedIndex:2020; }","duration":"236.032754ms","start":"2026-04-20T11:51:19.220082Z","end":"2026-04-20T11:51:19.456115Z","steps":["trace[995512227] 'read index received' (duration: 211.907732ms)","trace[995512227] 'applied index is now lower than readState.Index' (duration: 24.123942ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:51:19.456375Z","caller":"traceutil/trace.go:171","msg":"trace[2054551833] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"278.808636ms","start":"2026-04-20T11:51:19.177475Z","end":"2026-04-20T11:51:19.456283Z","steps":["trace[2054551833] 'process raft request' (duration: 278.535009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:19.456799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.343526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-20T11:51:19.456797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.69294ms","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-20T11:51:19.45686Z","caller":"traceutil/trace.go:171","msg":"trace[1123781795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1945; }","duration":"236.790412ms","start":"2026-04-20T11:51:19.220056Z","end":"2026-04-20T11:51:19.456846Z","steps":["trace[1123781795] 'agreement among raft nodes before linearized reading' (duration: 236.626048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:19.456844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.792759ms","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":"warn","ts":"2026-04-20T11:51:19.456866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.542919ms","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-20T11:51:19.456857Z","caller":"traceutil/trace.go:171","msg":"trace[106605347] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:1945; }","duration":"150.433629ms","start":"2026-04-20T11:51:19.306406Z","end":"2026-04-20T11:51:19.456839Z","steps":["trace[106605347] 'agreement among raft nodes before linearized reading' (duration: 150.279525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:19.45681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.274229ms","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-20T11:51:19.456941Z","caller":"traceutil/trace.go:171","msg":"trace[400530141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1945; }","duration":"136.920243ms","start":"2026-04-20T11:51:19.319998Z","end":"2026-04-20T11:51:19.456918Z","steps":["trace[400530141] 'agreement among raft nodes before linearized reading' (duration: 136.653686ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:51:19.457041Z","caller":"traceutil/trace.go:171","msg":"trace[292336453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1945; }","duration":"204.485963ms","start":"2026-04-20T11:51:19.252495Z","end":"2026-04-20T11:51:19.456981Z","steps":["trace[292336453] 'agreement among raft nodes before linearized reading' (duration: 203.968311ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:51:19.456954Z","caller":"traceutil/trace.go:171","msg":"trace[137135793] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1945; }","duration":"193.658142ms","start":"2026-04-20T11:51:19.263275Z","end":"2026-04-20T11:51:19.456933Z","steps":["trace[137135793] 'agreement among raft nodes before linearized reading' (duration: 193.437127ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:51:19.456386Z","caller":"traceutil/trace.go:171","msg":"trace[1983592231] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"287.000164ms","start":"2026-04-20T11:51:19.169364Z","end":"2026-04-20T11:51:19.456364Z","steps":["trace[1983592231] 'process raft request' (duration: 262.543424ms)","trace[1983592231] 'compare' (duration: 23.921227ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:51:19.578758Z","caller":"traceutil/trace.go:171","msg":"trace[2005735055] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"116.552742ms","start":"2026-04-20T11:51:19.462179Z","end":"2026-04-20T11:51:19.578732Z","steps":["trace[2005735055] 'process raft request' (duration: 101.600272ms)","trace[2005735055] 'compare' (duration: 14.757326ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:51:19.579674Z","caller":"traceutil/trace.go:171","msg":"trace[1221241630] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"116.50265ms","start":"2026-04-20T11:51:19.463141Z","end":"2026-04-20T11:51:19.579643Z","steps":["trace[1221241630] 'process raft request' (duration: 116.419828ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:51:19.579726Z","caller":"traceutil/trace.go:171","msg":"trace[1587037695] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"117.088085ms","start":"2026-04-20T11:51:19.462609Z","end":"2026-04-20T11:51:19.579697Z","steps":["trace[1587037695] 'process raft request' (duration: 116.083221ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:51:23.058189Z","caller":"traceutil/trace.go:171","msg":"trace[1763577439] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"196.205338ms","start":"2026-04-20T11:51:22.861955Z","end":"2026-04-20T11:51:23.05816Z","steps":["trace[1763577439] 'process raft request' (duration: 195.974173ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:51:23.110719Z","caller":"traceutil/trace.go:171","msg":"trace[246069332] linearizableReadLoop","detail":"{readStateIndex:2043; appliedIndex:2041; }","duration":"192.575743ms","start":"2026-04-20T11:51:22.918122Z","end":"2026-04-20T11:51:23.110697Z","steps":["trace[246069332] 'read index received' (duration: 139.794894ms)","trace[246069332] 'applied index is now lower than readState.Index' (duration: 52.779959ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:51:23.110824Z","caller":"traceutil/trace.go:171","msg":"trace[1069952817] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"235.689816ms","start":"2026-04-20T11:51:22.875123Z","end":"2026-04-20T11:51:23.110813Z","steps":["trace[1069952817] 'process raft request' (duration: 235.460981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:23.111096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.965572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T11:51:23.111153Z","caller":"traceutil/trace.go:171","msg":"trace[1852322773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1965; }","duration":"193.067914ms","start":"2026-04-20T11:51:22.91807Z","end":"2026-04-20T11:51:23.111138Z","steps":["trace[1852322773] 'agreement among raft nodes before linearized reading' (duration: 192.87954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:23.112601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.46821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T11:51:23.112691Z","caller":"traceutil/trace.go:171","msg":"trace[295887938] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1966; }","duration":"135.581323ms","start":"2026-04-20T11:51:22.977067Z","end":"2026-04-20T11:51:23.112648Z","steps":["trace[295887938] 'agreement among raft nodes before linearized reading' (duration: 135.419029ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:51:45.667009Z","caller":"traceutil/trace.go:171","msg":"trace[366417392] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"126.361149ms","start":"2026-04-20T11:51:45.540628Z","end":"2026-04-20T11:51:45.666989Z","steps":["trace[366417392] 'process raft request' (duration: 125.849468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:45.893235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.656854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T11:51:45.893313Z","caller":"traceutil/trace.go:171","msg":"trace[1844107330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2094; }","duration":"193.768936ms","start":"2026-04-20T11:51:45.699526Z","end":"2026-04-20T11:51:45.893295Z","steps":["trace[1844107330] 'range keys from in-memory index tree' (duration: 193.502861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:45.893375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.739025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T11:51:45.89345Z","caller":"traceutil/trace.go:171","msg":"trace[852974968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2094; }","duration":"193.836348ms","start":"2026-04-20T11:51:45.699596Z","end":"2026-04-20T11:51:45.893432Z","steps":["trace[852974968] 'range keys from in-memory index tree' (duration: 193.582872ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:51:47.110742Z","caller":"traceutil/trace.go:171","msg":"trace[219755212] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"116.974294ms","start":"2026-04-20T11:51:46.993752Z","end":"2026-04-20T11:51:47.110726Z","steps":["trace[219755212] 'process raft request' (duration: 116.865212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:47.528836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.794239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:51:47.528925Z","caller":"traceutil/trace.go:171","msg":"trace[1688520092] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2183; }","duration":"212.37466ms","start":"2026-04-20T11:51:47.316537Z","end":"2026-04-20T11:51:47.528911Z","steps":["trace[1688520092] 'read index received' (duration: 67.181034ms)","trace[1688520092] 'applied index is now lower than readState.Index' (duration: 145.192476ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:51:47.528994Z","caller":"traceutil/trace.go:171","msg":"trace[1324330846] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"271.53088ms","start":"2026-04-20T11:51:47.25741Z","end":"2026-04-20T11:51:47.528941Z","steps":["trace[1324330846] 'process raft request' (duration: 126.217841ms)","trace[1324330846] 'compare' (duration: 144.622026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:51:47.529343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.203369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T11:51:47.529578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.045943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T11:51:47.529614Z","caller":"traceutil/trace.go:171","msg":"trace[900989617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2101; }","duration":"213.126165ms","start":"2026-04-20T11:51:47.316479Z","end":"2026-04-20T11:51:47.529605Z","steps":["trace[900989617] 'agreement among raft nodes before linearized reading' (duration: 212.732007ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:51:47.529634Z","caller":"traceutil/trace.go:171","msg":"trace[711333461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2101; }","duration":"101.290071ms","start":"2026-04-20T11:51:47.428085Z","end":"2026-04-20T11:51:47.529375Z","steps":["trace[711333461] 'agreement among raft nodes before linearized reading' (duration: 101.209799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:48.260546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.785565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:51:48.260772Z","caller":"traceutil/trace.go:171","msg":"trace[2063325099] linearizableReadLoop","detail":"{readStateIndex:2186; appliedIndex:2184; }","duration":"576.616559ms","start":"2026-04-20T11:51:47.68414Z","end":"2026-04-20T11:51:48.260756Z","steps":["trace[2063325099] 'read index received' (duration: 164.563888ms)","trace[2063325099] 'applied index is now lower than readState.Index' (duration: 412.051621ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:51:48.260865Z","caller":"traceutil/trace.go:171","msg":"trace[1596004157] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"727.165439ms","start":"2026-04-20T11:51:47.533688Z","end":"2026-04-20T11:51:48.260853Z","steps":["trace[1596004157] 'process raft request' (duration: 314.981685ms)","trace[1596004157] 'compare' (duration: 411.337107ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:51:48.260922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:51:47.533674Z","time spent":"727.21493ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:51:48.261304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.202304ms","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-04-20T11:51:48.261387Z","caller":"traceutil/trace.go:171","msg":"trace[1619037504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2103; }","duration":"559.338857ms","start":"2026-04-20T11:51:47.70203Z","end":"2026-04-20T11:51:48.261369Z","steps":["trace[1619037504] 'agreement among raft nodes before linearized reading' (duration: 559.107602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:48.261429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:51:47.70201Z","time spent":"559.410038ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T11:51:48.261565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.304297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T11:51:48.261633Z","caller":"traceutil/trace.go:171","msg":"trace[770779710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2103; }","duration":"343.402098ms","start":"2026-04-20T11:51:47.918215Z","end":"2026-04-20T11:51:48.261617Z","steps":["trace[770779710] 'agreement among raft nodes before linearized reading' (duration: 343.206105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:48.261638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.407464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"warn","ts":"2026-04-20T11:51:48.261636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.471246ms","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-20T11:51:48.26167Z","caller":"traceutil/trace.go:171","msg":"trace[277949216] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2103; }","duration":"525.472774ms","start":"2026-04-20T11:51:47.736188Z","end":"2026-04-20T11:51:48.261661Z","steps":["trace[277949216] 'agreement among raft nodes before linearized reading' (duration: 525.377252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:48.261675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:51:47.91818Z","time spent":"343.48747ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T11:51:48.261699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:51:47.736171Z","time spent":"525.522426ms","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12408,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-20T11:51:48.261706Z","caller":"traceutil/trace.go:171","msg":"trace[888464226] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2103; }","duration":"577.553497ms","start":"2026-04-20T11:51:47.684128Z","end":"2026-04-20T11:51:48.261681Z","steps":["trace[888464226] 'agreement among raft nodes before linearized reading' (duration: 576.951786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:48.261839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.531847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T11:51:48.261947Z","caller":"traceutil/trace.go:171","msg":"trace[1377778304] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"589.042358ms","start":"2026-04-20T11:51:47.672892Z","end":"2026-04-20T11:51:48.261934Z","steps":["trace[1377778304] 'process raft request' (duration: 587.770063ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:51:48.261924Z","caller":"traceutil/trace.go:171","msg":"trace[1642094056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2103; }","duration":"347.65398ms","start":"2026-04-20T11:51:47.914256Z","end":"2026-04-20T11:51:48.26191Z","steps":["trace[1642094056] 'agreement among raft nodes before linearized reading' (duration: 347.12359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:51:48.262003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:51:47.914236Z","time spent":"347.757891ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T11:51:48.262037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:51:47.672869Z","time spent":"589.107119ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:51:48.262301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:51:47.684062Z","time spent":"578.21821ms","remote":"127.0.0.1:36166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-20T11:53:17.116377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.508727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:53:17.116643Z","caller":"traceutil/trace.go:171","msg":"trace[716323497] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"403.719857ms","start":"2026-04-20T11:53:16.712899Z","end":"2026-04-20T11:53:17.116619Z","steps":["trace[716323497] 'process raft request' (duration: 127.892497ms)","trace[716323497] 'compare' (duration: 275.404085ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:53:17.116683Z","caller":"traceutil/trace.go:171","msg":"trace[1837173814] transaction","detail":"{read_only:false; response_revision:2690; number_of_response:1; }","duration":"403.371873ms","start":"2026-04-20T11:53:16.713285Z","end":"2026-04-20T11:53:17.116657Z","steps":["trace[1837173814] 'process raft request' (duration: 403.221741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:17.116724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:53:16.712875Z","time spent":"403.813318ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:53:17.116814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:53:16.713113Z","time spent":"403.634706ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:53:17.117322Z","caller":"traceutil/trace.go:171","msg":"trace[1923008050] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"296.515363ms","start":"2026-04-20T11:53:16.820768Z","end":"2026-04-20T11:53:17.117283Z","steps":["trace[1923008050] 'process raft request' (duration: 295.787083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:17.117579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.706075ms","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-04-20T11:53:17.117332Z","caller":"traceutil/trace.go:171","msg":"trace[2019103543] linearizableReadLoop","detail":"{readStateIndex:2794; appliedIndex:2792; }","duration":"345.454852ms","start":"2026-04-20T11:53:16.771864Z","end":"2026-04-20T11:53:17.117319Z","steps":["trace[2019103543] 'read index received' (duration: 68.942162ms)","trace[2019103543] 'applied index is now lower than readState.Index' (duration: 276.51144ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:53:17.117751Z","caller":"traceutil/trace.go:171","msg":"trace[1839447016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2691; }","duration":"346.061369ms","start":"2026-04-20T11:53:16.771665Z","end":"2026-04-20T11:53:17.117726Z","steps":["trace[1839447016] 'agreement among raft nodes before linearized reading' (duration: 345.702175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:17.117998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:53:16.771652Z","time spent":"346.329032ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T11:53:17.118459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.584765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-20T11:53:17.118498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.955458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a80e7a6e7f90a4\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-20T11:53:17.118547Z","caller":"traceutil/trace.go:171","msg":"trace[691550159] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a80e7a6e7f90a4; range_end:; response_count:1; response_revision:2691; }","duration":"115.055409ms","start":"2026-04-20T11:53:17.003477Z","end":"2026-04-20T11:53:17.118533Z","steps":["trace[691550159] 'agreement among raft nodes before linearized reading' (duration: 114.875497ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:17.118578Z","caller":"traceutil/trace.go:171","msg":"trace[1868898920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2691; }","duration":"338.703976ms","start":"2026-04-20T11:53:16.779806Z","end":"2026-04-20T11:53:17.11851Z","steps":["trace[1868898920] 'agreement among raft nodes before linearized reading' (duration: 338.468133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:17.1189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:53:16.779788Z","time spent":"339.09127ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T11:56:31.6256Z","caller":"traceutil/trace.go:171","msg":"trace[1463547040] transaction","detail":"{read_only:false; response_revision:3878; number_of_response:1; }","duration":"136.516171ms","start":"2026-04-20T11:56:31.489064Z","end":"2026-04-20T11:56:31.62558Z","steps":["trace[1463547040] 'process raft request' (duration: 136.371248ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:20.40861Z","caller":"traceutil/trace.go:171","msg":"trace[770105542] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4441; }","duration":"156.360431ms","start":"2026-04-20T11:57:20.252229Z","end":"2026-04-20T11:57:20.40859Z","steps":["trace[770105542] 'process raft request' (duration: 93.103696ms)","trace[770105542] 'compare' (duration: 63.022431ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:57:20.408748Z","caller":"traceutil/trace.go:171","msg":"trace[1433858091] transaction","detail":"{read_only:false; response_revision:4442; number_of_response:1; }","duration":"108.447855ms","start":"2026-04-20T11:57:20.30027Z","end":"2026-04-20T11:57:20.408718Z","steps":["trace[1433858091] 'process raft request' (duration: 108.268262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:35.124541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.74164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:57:35.124733Z","caller":"traceutil/trace.go:171","msg":"trace[310900492] linearizableReadLoop","detail":"{readStateIndex:5012; appliedIndex:5011; }","duration":"231.165907ms","start":"2026-04-20T11:57:34.893546Z","end":"2026-04-20T11:57:35.124712Z","steps":["trace[310900492] 'read index received' (duration: 114.185353ms)","trace[310900492] 'applied index is now lower than readState.Index' (duration: 116.978864ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:57:35.124883Z","caller":"traceutil/trace.go:171","msg":"trace[1358446812] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"296.819215ms","start":"2026-04-20T11:57:34.828051Z","end":"2026-04-20T11:57:35.12487Z","steps":["trace[1358446812] 'process raft request' (duration: 179.670428ms)","trace[1358446812] 'compare' (duration: 116.513326ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:57:35.125119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.550024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T11:57:35.125204Z","caller":"traceutil/trace.go:171","msg":"trace[1181543882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4824; }","duration":"231.644825ms","start":"2026-04-20T11:57:34.893539Z","end":"2026-04-20T11:57:35.125184Z","steps":["trace[1181543882] 'agreement among raft nodes before linearized reading' (duration: 231.408111ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:35.179841Z","caller":"traceutil/trace.go:171","msg":"trace[348710537] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"179.025943ms","start":"2026-04-20T11:57:35.000787Z","end":"2026-04-20T11:57:35.179813Z","steps":["trace[348710537] 'process raft request' (duration: 178.939212ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:35.179834Z","caller":"traceutil/trace.go:171","msg":"trace[2056979358] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"245.459574ms","start":"2026-04-20T11:57:34.934332Z","end":"2026-04-20T11:57:35.179792Z","steps":["trace[2056979358] 'process raft request' (duration: 245.265601ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:35.179841Z","caller":"traceutil/trace.go:171","msg":"trace[106751172] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"245.101457ms","start":"2026-04-20T11:57:34.934714Z","end":"2026-04-20T11:57:35.179816Z","steps":["trace[106751172] 'process raft request' (duration: 244.979545ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:35.283426Z","caller":"traceutil/trace.go:171","msg":"trace[1330140321] linearizableReadLoop","detail":"{readStateIndex:5016; appliedIndex:5015; }","duration":"103.628372ms","start":"2026-04-20T11:57:35.179768Z","end":"2026-04-20T11:57:35.283396Z","steps":["trace[1330140321] 'read index received' (duration: 99.930083ms)","trace[1330140321] 'applied index is now lower than readState.Index' (duration: 3.696809ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:57:35.283472Z","caller":"traceutil/trace.go:171","msg":"trace[312018677] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"154.641935ms","start":"2026-04-20T11:57:35.128806Z","end":"2026-04-20T11:57:35.283448Z","steps":["trace[312018677] 'process raft request' (duration: 150.990047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:35.283681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.412644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T11:57:35.283883Z","caller":"traceutil/trace.go:171","msg":"trace[350660148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4828; }","duration":"104.666458ms","start":"2026-04-20T11:57:35.179204Z","end":"2026-04-20T11:57:35.28387Z","steps":["trace[350660148] 'agreement among raft nodes before linearized reading' (duration: 104.363003ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:35.430148Z","caller":"traceutil/trace.go:171","msg":"trace[1853817154] linearizableReadLoop","detail":"{readStateIndex:5019; appliedIndex:5017; }","duration":"105.605213ms","start":"2026-04-20T11:57:35.324513Z","end":"2026-04-20T11:57:35.430119Z","steps":["trace[1853817154] 'read index received' (duration: 28.264841ms)","trace[1853817154] 'applied index is now lower than readState.Index' (duration: 77.339132ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:57:35.430167Z","caller":"traceutil/trace.go:171","msg":"trace[1712404035] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"112.387691ms","start":"2026-04-20T11:57:35.31776Z","end":"2026-04-20T11:57:35.430148Z","steps":["trace[1712404035] 'process raft request' (duration: 112.188508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:35.430292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.772576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-20T11:57:35.430347Z","caller":"traceutil/trace.go:171","msg":"trace[1830325236] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4831; }","duration":"105.878918ms","start":"2026-04-20T11:57:35.324456Z","end":"2026-04-20T11:57:35.430335Z","steps":["trace[1830325236] 'agreement among raft nodes before linearized reading' (duration: 105.755436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:35.655493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.342429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:57:35.655623Z","caller":"traceutil/trace.go:171","msg":"trace[354630076] linearizableReadLoop","detail":"{readStateIndex:5024; appliedIndex:5023; }","duration":"140.434538ms","start":"2026-04-20T11:57:35.515164Z","end":"2026-04-20T11:57:35.655599Z","steps":["trace[354630076] 'read index received' (duration: 2.809585ms)","trace[354630076] 'applied index is now lower than readState.Index' (duration: 137.623013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:57:35.65584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.670111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-04-20T11:57:35.655893Z","caller":"traceutil/trace.go:171","msg":"trace[681166943] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4836; }","duration":"140.757653ms","start":"2026-04-20T11:57:35.515121Z","end":"2026-04-20T11:57:35.655878Z","steps":["trace[681166943] 'agreement among raft nodes before linearized reading' (duration: 140.53899ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:35.6559Z","caller":"traceutil/trace.go:171","msg":"trace[1625395874] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"193.946941ms","start":"2026-04-20T11:57:35.461916Z","end":"2026-04-20T11:57:35.655863Z","steps":["trace[1625395874] 'process raft request' (duration: 56.163495ms)","trace[1625395874] 'compare' (duration: 137.096525ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:57:35.90274Z","caller":"traceutil/trace.go:171","msg":"trace[1817484685] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"205.660927ms","start":"2026-04-20T11:57:35.697045Z","end":"2026-04-20T11:57:35.902706Z","steps":["trace[1817484685] 'process raft request' (duration: 190.871241ms)","trace[1817484685] 'compare' (duration: 14.464021ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:57:36.086509Z","caller":"traceutil/trace.go:171","msg":"trace[55778263] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"113.981255ms","start":"2026-04-20T11:57:35.972486Z","end":"2026-04-20T11:57:36.086467Z","steps":["trace[55778263] 'process raft request' (duration: 112.900067ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:38.350594Z","caller":"traceutil/trace.go:171","msg":"trace[297920371] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"100.680197ms","start":"2026-04-20T11:57:38.249893Z","end":"2026-04-20T11:57:38.350574Z","steps":["trace[297920371] 'process raft request' (duration: 100.523874ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:43.612631Z","caller":"traceutil/trace.go:171","msg":"trace[398596298] linearizableReadLoop","detail":"{readStateIndex:5156; appliedIndex:5155; }","duration":"167.797478ms","start":"2026-04-20T11:57:43.44481Z","end":"2026-04-20T11:57:43.612607Z","steps":["trace[398596298] 'read index received' (duration: 165.566884ms)","trace[398596298] 'applied index is now lower than readState.Index' (duration: 2.229924ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:57:43.612744Z","caller":"traceutil/trace.go:171","msg":"trace[307185745] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"169.060598ms","start":"2026-04-20T11:57:43.443638Z","end":"2026-04-20T11:57:43.612699Z","steps":["trace[307185745] 'process raft request' (duration: 166.753912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:43.612831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.008381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-20T11:57:43.612869Z","caller":"traceutil/trace.go:171","msg":"trace[2043240774] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4963; }","duration":"168.091643ms","start":"2026-04-20T11:57:43.444767Z","end":"2026-04-20T11:57:43.612859Z","steps":["trace[2043240774] 'agreement among raft nodes before linearized reading' (duration: 167.91994ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:49.149773Z","caller":"traceutil/trace.go:171","msg":"trace[1289367155] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"276.288785ms","start":"2026-04-20T11:57:48.873441Z","end":"2026-04-20T11:57:49.14973Z","steps":["trace[1289367155] 'process raft request' (duration: 276.134753ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:49.172674Z","caller":"traceutil/trace.go:171","msg":"trace[484724545] linearizableReadLoop","detail":"{readStateIndex:5202; appliedIndex:5201; }","duration":"154.195501ms","start":"2026-04-20T11:57:49.018454Z","end":"2026-04-20T11:57:49.172649Z","steps":["trace[484724545] 'read index received' (duration: 131.424396ms)","trace[484724545] 'applied index is now lower than readState.Index' (duration: 22.770125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:57:49.172914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.438244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:57:49.17296Z","caller":"traceutil/trace.go:171","msg":"trace[1872286433] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:5008; }","duration":"154.545496ms","start":"2026-04-20T11:57:49.018403Z","end":"2026-04-20T11:57:49.172949Z","steps":["trace[1872286433] 'agreement among raft nodes before linearized reading' (duration: 154.447395ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:49.173145Z","caller":"traceutil/trace.go:171","msg":"trace[1366532130] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"267.140621ms","start":"2026-04-20T11:57:48.905997Z","end":"2026-04-20T11:57:49.173137Z","steps":["trace[1366532130] 'process raft request' (duration: 266.529742ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:49.591542Z","caller":"traceutil/trace.go:171","msg":"trace[1943202593] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"103.614033ms","start":"2026-04-20T11:57:49.487895Z","end":"2026-04-20T11:57:49.591509Z","steps":["trace[1943202593] 'process raft request' (duration: 11.710752ms)","trace[1943202593] 'compare' (duration: 91.22323ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:58:37.856Z","caller":"traceutil/trace.go:171","msg":"trace[1803265394] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"107.108211ms","start":"2026-04-20T11:58:37.748868Z","end":"2026-04-20T11:58:37.855976Z","steps":["trace[1803265394] 'process raft request' (duration: 106.637054ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:56.541936Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2898} {"level":"info","ts":"2026-04-20T11:58:56.656403Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2898,"took":"112.665438ms","hash":1685849318,"current-db-size-bytes":53772288,"current-db-size":"54 MB","current-db-size-in-use-bytes":29110272,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-20T11:58:56.656484Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1685849318,"revision":2898,"compact-revision":-1} {"level":"info","ts":"2026-04-20T12:01:28.505566Z","caller":"traceutil/trace.go:171","msg":"trace[1850139747] transaction","detail":"{read_only:false; response_revision:6638; number_of_response:1; }","duration":"106.646984ms","start":"2026-04-20T12:01:28.398891Z","end":"2026-04-20T12:01:28.505538Z","steps":["trace[1850139747] 'process raft request' (duration: 106.491262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:01:34.304594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.53123ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:01:34.304741Z","caller":"traceutil/trace.go:171","msg":"trace[625278787] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:6666; }","duration":"103.698103ms","start":"2026-04-20T12:01:34.201018Z","end":"2026-04-20T12:01:34.304716Z","steps":["trace[625278787] 'range keys from in-memory index tree' (duration: 103.50784ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:34.30547Z","caller":"traceutil/trace.go:171","msg":"trace[2125220885] transaction","detail":"{read_only:false; response_revision:6667; number_of_response:1; }","duration":"135.865458ms","start":"2026-04-20T12:01:34.169578Z","end":"2026-04-20T12:01:34.305444Z","steps":["trace[2125220885] 'process raft request' (duration: 45.778872ms)","trace[2125220885] 'compare' (duration: 89.716912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:01:34.531813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.29434ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T12:01:34.531898Z","caller":"traceutil/trace.go:171","msg":"trace[1330761236] linearizableReadLoop","detail":"{readStateIndex:6917; appliedIndex:6916; }","duration":"102.265185ms","start":"2026-04-20T12:01:34.429616Z","end":"2026-04-20T12:01:34.531881Z","steps":["trace[1330761236] 'read index received' (duration: 40.751µs)","trace[1330761236] 'applied index is now lower than readState.Index' (duration: 102.222954ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:01:34.531959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.340556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:01:34.531981Z","caller":"traceutil/trace.go:171","msg":"trace[1743728542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6668; }","duration":"102.410386ms","start":"2026-04-20T12:01:34.429564Z","end":"2026-04-20T12:01:34.531974Z","steps":["trace[1743728542] 'agreement among raft nodes before linearized reading' (duration: 102.358295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:01:34.854798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.663878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T12:01:34.855301Z","caller":"traceutil/trace.go:171","msg":"trace[689895191] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:6669; }","duration":"164.222536ms","start":"2026-04-20T12:01:34.691054Z","end":"2026-04-20T12:01:34.855276Z","steps":["trace[689895191] 'count revisions from in-memory index tree' (duration: 163.579088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:01:34.855008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.391318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-20T12:01:34.855044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.034214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T12:01:34.856011Z","caller":"traceutil/trace.go:171","msg":"trace[334728973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6669; }","duration":"179.423094ms","start":"2026-04-20T12:01:34.676553Z","end":"2026-04-20T12:01:34.855977Z","steps":["trace[334728973] 'range keys from in-memory index tree' (duration: 178.196766ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:34.856137Z","caller":"traceutil/trace.go:171","msg":"trace[1313724146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6669; }","duration":"155.177679ms","start":"2026-04-20T12:01:34.700922Z","end":"2026-04-20T12:01:34.8561Z","steps":["trace[1313724146] 'range keys from in-memory index tree' (duration: 153.576498ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:35.062423Z","caller":"traceutil/trace.go:171","msg":"trace[1162326612] transaction","detail":"{read_only:false; response_revision:6671; number_of_response:1; }","duration":"202.485271ms","start":"2026-04-20T12:01:34.859901Z","end":"2026-04-20T12:01:35.062386Z","steps":["trace[1162326612] 'process raft request' (duration: 105.299654ms)","trace[1162326612] 'compare' (duration: 96.889823ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:01:35.06245Z","caller":"traceutil/trace.go:171","msg":"trace[1478875969] linearizableReadLoop","detail":"{readStateIndex:6921; appliedIndex:6918; }","duration":"184.040832ms","start":"2026-04-20T12:01:34.878383Z","end":"2026-04-20T12:01:35.062424Z","steps":["trace[1478875969] 'read index received' (duration: 21.312975ms)","trace[1478875969] 'applied index is now lower than readState.Index' (duration: 162.726677ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:01:35.062478Z","caller":"traceutil/trace.go:171","msg":"trace[1262833821] transaction","detail":"{read_only:false; response_revision:6672; number_of_response:1; }","duration":"202.453431ms","start":"2026-04-20T12:01:34.859986Z","end":"2026-04-20T12:01:35.062439Z","steps":["trace[1262833821] 'process raft request' (duration: 202.261808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:01:35.062692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.293855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-20T12:01:35.062563Z","caller":"traceutil/trace.go:171","msg":"trace[1858421474] transaction","detail":"{read_only:false; response_revision:6673; number_of_response:1; }","duration":"164.364677ms","start":"2026-04-20T12:01:34.898174Z","end":"2026-04-20T12:01:35.062539Z","steps":["trace[1858421474] 'process raft request' (duration: 164.183075ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:35.062738Z","caller":"traceutil/trace.go:171","msg":"trace[1618655472] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6673; }","duration":"184.392776ms","start":"2026-04-20T12:01:34.878335Z","end":"2026-04-20T12:01:35.062728Z","steps":["trace[1618655472] 'agreement among raft nodes before linearized reading' (duration: 184.206444ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:35.5197Z","caller":"traceutil/trace.go:171","msg":"trace[1615873409] transaction","detail":"{read_only:false; response_revision:6674; number_of_response:1; }","duration":"260.414359ms","start":"2026-04-20T12:01:35.259261Z","end":"2026-04-20T12:01:35.519675Z","steps":["trace[1615873409] 'process raft request' (duration: 260.260137ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:35.519761Z","caller":"traceutil/trace.go:171","msg":"trace[1273037029] linearizableReadLoop","detail":"{readStateIndex:6923; appliedIndex:6923; }","duration":"103.54839ms","start":"2026-04-20T12:01:35.416186Z","end":"2026-04-20T12:01:35.519734Z","steps":["trace[1273037029] 'read index received' (duration: 103.53761ms)","trace[1273037029] 'applied index is now lower than readState.Index' (duration: 8.67µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:01:35.519994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.787344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-04-20T12:01:35.520043Z","caller":"traceutil/trace.go:171","msg":"trace[1304725512] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6674; }","duration":"103.875164ms","start":"2026-04-20T12:01:35.416152Z","end":"2026-04-20T12:01:35.520027Z","steps":["trace[1304725512] 'agreement among raft nodes before linearized reading' (duration: 103.703272ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:35.622645Z","caller":"traceutil/trace.go:171","msg":"trace[425717698] linearizableReadLoop","detail":"{readStateIndex:6924; appliedIndex:6923; }","duration":"102.798669ms","start":"2026-04-20T12:01:35.519823Z","end":"2026-04-20T12:01:35.622622Z","steps":["trace[425717698] 'read index received' (duration: 82.606698ms)","trace[425717698] 'applied index is now lower than readState.Index' (duration: 20.191261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:01:35.622814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.23985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-04-20T12:01:35.622785Z","caller":"traceutil/trace.go:171","msg":"trace[1688996017] transaction","detail":"{read_only:false; response_revision:6675; number_of_response:1; }","duration":"168.866885ms","start":"2026-04-20T12:01:35.453875Z","end":"2026-04-20T12:01:35.622742Z","steps":["trace[1688996017] 'process raft request' (duration: 148.623983ms)","trace[1688996017] 'compare' (duration: 20.012259ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:01:35.622851Z","caller":"traceutil/trace.go:171","msg":"trace[1660059967] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6675; }","duration":"118.333411ms","start":"2026-04-20T12:01:35.504506Z","end":"2026-04-20T12:01:35.62284Z","steps":["trace[1660059967] 'agreement among raft nodes before linearized reading' (duration: 118.202359ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:03:56.549743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5583} {"level":"info","ts":"2026-04-20T12:03:56.644683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5583,"took":"93.461249ms","hash":598001556,"current-db-size-bytes":53772288,"current-db-size":"54 MB","current-db-size-in-use-bytes":18530304,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T12:03:56.644739Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":598001556,"revision":5583,"compact-revision":2898}