{"level":"warn","ts":"2026-04-20T04:51:15.507035Z","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-20T04:51:15.507139Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209: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.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209: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-20T04:51:15.50721Z","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-20T04:51:15.507223Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-20T04:51:15.507242Z","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-20T04:51:15.507881Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]} {"level":"info","ts":"2026-04-20T04:51:15.507964Z","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.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209: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-20T04:51:15.512999Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.818708ms"} {"level":"info","ts":"2026-04-20T04:51:15.524558Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"} {"level":"info","ts":"2026-04-20T04:51:15.524648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T04:51:15.524673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"} {"level":"info","ts":"2026-04-20T04:51:15.524684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T04:51:15.524702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"} {"level":"info","ts":"2026-04-20T04:51:15.524742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"warn","ts":"2026-04-20T04:51:15.530127Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T04:51:15.531714Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T04:51:15.532952Z","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-20T04:51:15.536381Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T04:51:15.536662Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T04:51:15.53689Z","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-20T04:51:15.537079Z","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-20T04:51:15.537141Z","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-20T04:51:15.537423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"info","ts":"2026-04-20T04:51:15.538907Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-20T04:51:15.540847Z","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-20T04:51:15.54113Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-20T04:51:15.541234Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T04:51:15.541256Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-20T04:51:15.541185Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T04:51:15.925497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T04:51:15.925555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T04:51:15.9256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"} {"level":"info","ts":"2026-04-20T04:51:15.92562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"} {"level":"info","ts":"2026-04-20T04:51:15.925631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-20T04:51:15.925647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"} {"level":"info","ts":"2026-04-20T04:51:15.925689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-20T04:51:15.939739Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T04:51:15.939767Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:51:15.939778Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:51:15.939839Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:51:15.94006Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T04:51:15.940137Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T04:51:15.940517Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:51:15.94079Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:51:15.940821Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:51:15.943135Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T04:51:15.945996Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"} {"level":"info","ts":"2026-04-20T04:51:31.274911Z","caller":"traceutil/trace.go:171","msg":"trace[1730149586] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"137.674004ms","start":"2026-04-20T04:51:31.137212Z","end":"2026-04-20T04:51:31.274886Z","steps":["trace[1730149586] 'process raft request' (duration: 123.623822ms)","trace[1730149586] 'compare' (duration: 13.930929ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:32.171439Z","caller":"traceutil/trace.go:171","msg":"trace[2052413707] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"145.351067ms","start":"2026-04-20T04:51:32.02606Z","end":"2026-04-20T04:51:32.171411Z","steps":["trace[2052413707] 'process raft request' (duration: 145.11749ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:34.312192Z","caller":"traceutil/trace.go:171","msg":"trace[373866946] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"407.271899ms","start":"2026-04-20T04:51:33.904898Z","end":"2026-04-20T04:51:34.31217Z","steps":["trace[373866946] 'read index received' (duration: 407.026583ms)","trace[373866946] 'applied index is now lower than readState.Index' (duration: 244.296µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:34.312289Z","caller":"traceutil/trace.go:171","msg":"trace[1481510653] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"715.029806ms","start":"2026-04-20T04:51:33.59722Z","end":"2026-04-20T04:51:34.31225Z","steps":["trace[1481510653] 'process raft request' (duration: 714.82056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:34.312363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.450024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T04:51:34.312444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.427711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T04:51:34.312458Z","caller":"traceutil/trace.go:171","msg":"trace[1039995048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"407.598948ms","start":"2026-04-20T04:51:33.904845Z","end":"2026-04-20T04:51:34.312444Z","steps":["trace[1039995048] 'agreement among raft nodes before linearized reading' (duration: 407.440343ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:34.312477Z","caller":"traceutil/trace.go:171","msg":"trace[1054708675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"136.466542ms","start":"2026-04-20T04:51:34.176001Z","end":"2026-04-20T04:51:34.312467Z","steps":["trace[1054708675] 'agreement among raft nodes before linearized reading' (duration: 136.37098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:34.312501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:33.904823Z","time spent":"407.667711ms","remote":"127.0.0.1:49632","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":"warn","ts":"2026-04-20T04:51:34.312935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:33.597185Z","time spent":"715.18444ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:51:34.980049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592276850755496,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T04:51:35.40302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.798072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:51:35.403268Z","caller":"traceutil/trace.go:171","msg":"trace[1627620080] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"1.086259322s","start":"2026-04-20T04:51:34.316973Z","end":"2026-04-20T04:51:35.403233Z","steps":["trace[1627620080] 'process raft request' (duration: 840.083139ms)","trace[1627620080] 'compare' (duration: 245.679209ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:35.403328Z","caller":"traceutil/trace.go:171","msg":"trace[275419431] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"1.08584396s","start":"2026-04-20T04:51:34.317445Z","end":"2026-04-20T04:51:35.403289Z","steps":["trace[275419431] 'process raft request' (duration: 1.085668266s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:35.403459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:34.316955Z","time spent":"1.086396196s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:51:35.403529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:34.317422Z","time spent":"1.085995915s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:51:35.406918Z","caller":"traceutil/trace.go:171","msg":"trace[897208921] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:432; }","duration":"927.4322ms","start":"2026-04-20T04:51:34.479466Z","end":"2026-04-20T04:51:35.406898Z","steps":["trace[897208921] 'read index received' (duration: 677.603999ms)","trace[897208921] 'applied index is now lower than readState.Index' (duration: 249.827111ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:51:35.407137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.667416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T04:51:35.407141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.294738ms","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-20T04:51:35.4072Z","caller":"traceutil/trace.go:171","msg":"trace[1548555652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"927.774729ms","start":"2026-04-20T04:51:34.479407Z","end":"2026-04-20T04:51:35.407182Z","steps":["trace[1548555652] 'agreement among raft nodes before linearized reading' (duration: 927.675406ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:35.407214Z","caller":"traceutil/trace.go:171","msg":"trace[1067861987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:420; }","duration":"918.410191ms","start":"2026-04-20T04:51:34.488784Z","end":"2026-04-20T04:51:35.407194Z","steps":["trace[1067861987] 'agreement among raft nodes before linearized reading' (duration: 918.283588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:35.407265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:34.479376Z","time spent":"927.876112ms","remote":"127.0.0.1:49338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T04:51:35.407265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:34.488759Z","time spent":"918.493003ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T04:51:41.234908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.311442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:51:41.23502Z","caller":"traceutil/trace.go:171","msg":"trace[1631831607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:463; }","duration":"755.478557ms","start":"2026-04-20T04:51:40.479518Z","end":"2026-04-20T04:51:41.234997Z","steps":["trace[1631831607] 'range keys from in-memory index tree' (duration: 755.21842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:41.235071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:40.479497Z","time spent":"755.563809ms","remote":"127.0.0.1:49338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T04:51:50.623289Z","caller":"traceutil/trace.go:171","msg":"trace[594389316] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"151.148259ms","start":"2026-04-20T04:51:50.472111Z","end":"2026-04-20T04:51:50.623259Z","steps":["trace[594389316] 'process raft request' (duration: 151.002144ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:54.482233Z","caller":"traceutil/trace.go:171","msg":"trace[1950615144] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"491.169493ms","start":"2026-04-20T04:51:53.991023Z","end":"2026-04-20T04:51:54.482193Z","steps":["trace[1950615144] 'process raft request' (duration: 490.974287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:54.482476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:53.990988Z","time spent":"491.367947ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:51:54.488246Z","caller":"traceutil/trace.go:171","msg":"trace[1858606627] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"490.814803ms","start":"2026-04-20T04:51:53.997392Z","end":"2026-04-20T04:51:54.488207Z","steps":["trace[1858606627] 'process raft request' (duration: 490.608118ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:54.489273Z","caller":"traceutil/trace.go:171","msg":"trace[947845797] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"487.570724ms","start":"2026-04-20T04:51:54.000685Z","end":"2026-04-20T04:51:54.488255Z","steps":["trace[947845797] 'process raft request' (duration: 487.441581ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:54.489306Z","caller":"traceutil/trace.go:171","msg":"trace[2002914382] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"466.80046ms","start":"2026-04-20T04:51:54.021995Z","end":"2026-04-20T04:51:54.488795Z","steps":["trace[2002914382] 'process raft request' (duration: 466.189513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:54.489105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:53.997367Z","time spent":"491.388998ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:51:54.489703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:54.000647Z","time spent":"488.720605ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:51:54.48999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:54.021965Z","time spent":"467.471118ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:51:54.490185Z","caller":"traceutil/trace.go:171","msg":"trace[1563395699] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"469.494392ms","start":"2026-04-20T04:51:54.018793Z","end":"2026-04-20T04:51:54.488287Z","steps":["trace[1563395699] 'process raft request' (duration: 469.361569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:54.490285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:54.018766Z","time spent":"471.469846ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:51:54.490964Z","caller":"traceutil/trace.go:171","msg":"trace[964492500] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"422.31164ms","start":"2026-04-20T04:51:54.06861Z","end":"2026-04-20T04:51:54.490922Z","steps":["trace[964492500] 'process raft request' (duration: 419.637837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:54.491069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:54.068558Z","time spent":"422.461753ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:51:54.491345Z","caller":"traceutil/trace.go:171","msg":"trace[1769455381] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"418.393343ms","start":"2026-04-20T04:51:54.072642Z","end":"2026-04-20T04:51:54.491035Z","steps":["trace[1769455381] 'process raft request' (duration: 418.065744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:54.491489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:54.072613Z","time spent":"418.804365ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:51:54.491999Z","caller":"traceutil/trace.go:171","msg":"trace[1400759662] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"418.429085ms","start":"2026-04-20T04:51:54.073164Z","end":"2026-04-20T04:51:54.491593Z","steps":["trace[1400759662] 'process raft request' (duration: 417.734996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:54.492215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:54.073134Z","time spent":"418.98718ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:51:54.492592Z","caller":"traceutil/trace.go:171","msg":"trace[1312511066] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"409.100642ms","start":"2026-04-20T04:51:54.083445Z","end":"2026-04-20T04:51:54.492545Z","steps":["trace[1312511066] 'process raft request' (duration: 408.440274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:54.492769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:54.083417Z","time spent":"409.274886ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:51:54.495442Z","caller":"traceutil/trace.go:171","msg":"trace[1403000524] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"404.186757ms","start":"2026-04-20T04:51:54.089649Z","end":"2026-04-20T04:51:54.493835Z","steps":["trace[1403000524] 'process raft request' (duration: 403.775096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:54.495519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:54.089627Z","time spent":"405.861793ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:51:54.496066Z","caller":"traceutil/trace.go:171","msg":"trace[855398050] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"383.625237ms","start":"2026-04-20T04:51:54.112408Z","end":"2026-04-20T04:51:54.496033Z","steps":["trace[855398050] 'process raft request' (duration: 381.402727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:54.496208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:54.112366Z","time spent":"383.777193ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:51:54.498586Z","caller":"traceutil/trace.go:171","msg":"trace[102449020] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"287.209028ms","start":"2026-04-20T04:51:54.208929Z","end":"2026-04-20T04:51:54.496138Z","steps":["trace[102449020] 'process raft request' (duration: 286.600622ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:54.747756Z","caller":"traceutil/trace.go:171","msg":"trace[1441838558] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"194.624141ms","start":"2026-04-20T04:51:54.553097Z","end":"2026-04-20T04:51:54.747721Z","steps":["trace[1441838558] 'process raft request' (duration: 141.707252ms)","trace[1441838558] 'compare' (duration: 52.646642ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:54.747766Z","caller":"traceutil/trace.go:171","msg":"trace[1823868491] transaction","detail":"{read_only:false; number_of_response:1; response_revision:554; }","duration":"192.470442ms","start":"2026-04-20T04:51:54.555268Z","end":"2026-04-20T04:51:54.747738Z","steps":["trace[1823868491] 'process raft request' (duration: 192.36176ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:54.747959Z","caller":"traceutil/trace.go:171","msg":"trace[1920801995] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"189.684426ms","start":"2026-04-20T04:51:54.558247Z","end":"2026-04-20T04:51:54.747931Z","steps":["trace[1920801995] 'process raft request' (duration: 189.43553ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:54.74818Z","caller":"traceutil/trace.go:171","msg":"trace[452752172] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"167.434093ms","start":"2026-04-20T04:51:54.580735Z","end":"2026-04-20T04:51:54.748169Z","steps":["trace[452752172] 'process raft request' (duration: 167.34848ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:54.748181Z","caller":"traceutil/trace.go:171","msg":"trace[1052789724] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"189.180023ms","start":"2026-04-20T04:51:54.558981Z","end":"2026-04-20T04:51:54.748161Z","steps":["trace[1052789724] 'process raft request' (duration: 188.942086ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:54.761753Z","caller":"traceutil/trace.go:171","msg":"trace[1336739839] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:573; }","duration":"123.516567ms","start":"2026-04-20T04:51:54.6382Z","end":"2026-04-20T04:51:54.761716Z","steps":["trace[1336739839] 'read index received' (duration: 56.551947ms)","trace[1336739839] 'applied index is now lower than readState.Index' (duration: 66.96353ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:54.761818Z","caller":"traceutil/trace.go:171","msg":"trace[204061548] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"158.16664ms","start":"2026-04-20T04:51:54.603625Z","end":"2026-04-20T04:51:54.761792Z","steps":["trace[204061548] 'process raft request' (duration: 157.833582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:54.762032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.806564ms","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-20T04:51:54.762071Z","caller":"traceutil/trace.go:171","msg":"trace[2136964747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"123.904238ms","start":"2026-04-20T04:51:54.638156Z","end":"2026-04-20T04:51:54.76206Z","steps":["trace[2136964747] 'agreement among raft nodes before linearized reading' (duration: 123.699303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:55.096247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.689035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:51:55.096619Z","caller":"traceutil/trace.go:171","msg":"trace[358819830] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"293.054017ms","start":"2026-04-20T04:51:54.803331Z","end":"2026-04-20T04:51:55.096385Z","steps":["trace[358819830] 'process raft request' (duration: 81.102275ms)","trace[358819830] 'compare' (duration: 211.53657ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:55.150766Z","caller":"traceutil/trace.go:171","msg":"trace[1037689273] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"344.622839ms","start":"2026-04-20T04:51:54.806123Z","end":"2026-04-20T04:51:55.150746Z","steps":["trace[1037689273] 'process raft request' (duration: 344.190797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:55.150919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:54.80609Z","time spent":"344.725812ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:51:55.153274Z","caller":"traceutil/trace.go:171","msg":"trace[1403123826] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"307.484708ms","start":"2026-04-20T04:51:54.845755Z","end":"2026-04-20T04:51:55.15324Z","steps":["trace[1403123826] 'process raft request' (duration: 304.910669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:55.153418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:54.845733Z","time spent":"307.622652ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:51:55.334054Z","caller":"traceutil/trace.go:171","msg":"trace[497808269] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"225.567412ms","start":"2026-04-20T04:51:55.108429Z","end":"2026-04-20T04:51:55.333997Z","steps":["trace[497808269] 'process raft request' (duration: 185.975156ms)","trace[497808269] 'compare' (duration: 39.240457ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:55.334103Z","caller":"traceutil/trace.go:171","msg":"trace[1540132306] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"167.77027ms","start":"2026-04-20T04:51:55.166302Z","end":"2026-04-20T04:51:55.334072Z","steps":["trace[1540132306] 'process raft request' (duration: 167.596075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:55.720358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.975325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:51:55.72069Z","caller":"traceutil/trace.go:171","msg":"trace[65697596] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:591; }","duration":"230.954639ms","start":"2026-04-20T04:51:55.489714Z","end":"2026-04-20T04:51:55.720669Z","steps":["trace[65697596] 'read index received' (duration: 196.588035ms)","trace[65697596] 'applied index is now lower than readState.Index' (duration: 34.365464ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:55.720688Z","caller":"traceutil/trace.go:171","msg":"trace[1732984583] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"339.782627ms","start":"2026-04-20T04:51:55.380857Z","end":"2026-04-20T04:51:55.720639Z","steps":["trace[1732984583] 'process raft request' (duration: 96.412781ms)","trace[1732984583] 'compare' (duration: 242.771679ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:55.720761Z","caller":"traceutil/trace.go:171","msg":"trace[1971426013] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"335.49941ms","start":"2026-04-20T04:51:55.385224Z","end":"2026-04-20T04:51:55.720723Z","steps":["trace[1971426013] 'process raft request' (duration: 335.292045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:55.720893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.162404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T04:51:55.720918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:55.380836Z","time spent":"339.954652ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:51:55.720934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.363908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T04:51:55.720943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.735193ms","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-20T04:51:55.720967Z","caller":"traceutil/trace.go:171","msg":"trace[2047314324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"231.285487ms","start":"2026-04-20T04:51:55.489663Z","end":"2026-04-20T04:51:55.720949Z","steps":["trace[2047314324] 'agreement among raft nodes before linearized reading' (duration: 231.138083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:55.720971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:55.38519Z","time spent":"335.668945ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:51:55.721003Z","caller":"traceutil/trace.go:171","msg":"trace[224416821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"155.814835ms","start":"2026-04-20T04:51:55.56517Z","end":"2026-04-20T04:51:55.720985Z","steps":["trace[224416821] 'agreement among raft nodes before linearized reading' (duration: 155.716632ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:55.720973Z","caller":"traceutil/trace.go:171","msg":"trace[862692139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"217.44257ms","start":"2026-04-20T04:51:55.50352Z","end":"2026-04-20T04:51:55.720963Z","steps":["trace[862692139] 'agreement among raft nodes before linearized reading' (duration: 217.283046ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:55.720988Z","caller":"traceutil/trace.go:171","msg":"trace[1385086294] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"315.599699ms","start":"2026-04-20T04:51:55.405329Z","end":"2026-04-20T04:51:55.720929Z","steps":["trace[1385086294] 'process raft request' (duration: 315.283101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:55.721224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:55.405284Z","time spent":"315.820265ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:51:55.721921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.085132ms","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-20T04:51:55.721985Z","caller":"traceutil/trace.go:171","msg":"trace[1882660946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"156.224757ms","start":"2026-04-20T04:51:55.565744Z","end":"2026-04-20T04:51:55.721969Z","steps":["trace[1882660946] 'agreement among raft nodes before linearized reading' (duration: 155.710263ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:56.184131Z","caller":"traceutil/trace.go:171","msg":"trace[1642930132] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"214.775779ms","start":"2026-04-20T04:51:55.969328Z","end":"2026-04-20T04:51:56.184104Z","steps":["trace[1642930132] 'process raft request' (duration: 214.404308ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:56.839345Z","caller":"traceutil/trace.go:171","msg":"trace[75414495] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"103.911565ms","start":"2026-04-20T04:51:56.735405Z","end":"2026-04-20T04:51:56.839316Z","steps":["trace[75414495] 'process raft request' (duration: 85.185556ms)","trace[75414495] 'compare' (duration: 18.382999ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:57.026807Z","caller":"traceutil/trace.go:171","msg":"trace[421677910] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"129.715996ms","start":"2026-04-20T04:51:56.897034Z","end":"2026-04-20T04:51:57.02675Z","steps":["trace[421677910] 'process raft request' (duration: 95.757453ms)","trace[421677910] 'compare' (duration: 33.187652ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:57.03262Z","caller":"traceutil/trace.go:171","msg":"trace[1215907557] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"115.342046ms","start":"2026-04-20T04:51:56.917219Z","end":"2026-04-20T04:51:57.032561Z","steps":["trace[1215907557] 'read index received' (duration: 75.739308ms)","trace[1215907557] 'applied index is now lower than readState.Index' (duration: 39.601547ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:57.032706Z","caller":"traceutil/trace.go:171","msg":"trace[1291443173] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"131.230238ms","start":"2026-04-20T04:51:56.901415Z","end":"2026-04-20T04:51:57.032645Z","steps":["trace[1291443173] 'process raft request' (duration: 125.492262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:57.032831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.587233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-04-20T04:51:57.032871Z","caller":"traceutil/trace.go:171","msg":"trace[2052921841] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:607; }","duration":"115.686875ms","start":"2026-04-20T04:51:56.917171Z","end":"2026-04-20T04:51:57.032858Z","steps":["trace[2052921841] 'agreement among raft nodes before linearized reading' (duration: 115.528721ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:57.209962Z","caller":"traceutil/trace.go:171","msg":"trace[279925003] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"127.906467ms","start":"2026-04-20T04:51:57.08202Z","end":"2026-04-20T04:51:57.209927Z","steps":["trace[279925003] 'process raft request' (duration: 120.157277ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:01.871432Z","caller":"traceutil/trace.go:171","msg":"trace[219184791] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"179.633082ms","start":"2026-04-20T04:52:01.691748Z","end":"2026-04-20T04:52:01.871381Z","steps":["trace[219184791] 'process raft request' (duration: 173.110005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:02.331795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.333137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:52:02.332138Z","caller":"traceutil/trace.go:171","msg":"trace[1553353978] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"454.194276ms","start":"2026-04-20T04:52:01.877914Z","end":"2026-04-20T04:52:02.332108Z","steps":["trace[1553353978] 'process raft request' (duration: 454.044172ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:02.332115Z","caller":"traceutil/trace.go:171","msg":"trace[1008452393] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"455.983945ms","start":"2026-04-20T04:52:01.876067Z","end":"2026-04-20T04:52:02.33205Z","steps":["trace[1008452393] 'process raft request' (duration: 351.245449ms)","trace[1008452393] 'compare' (duration: 104.109849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:52:02.332265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:01.877886Z","time spent":"454.32393ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:52:02.332363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:01.876044Z","time spent":"456.197041ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:52:02.332413Z","caller":"traceutil/trace.go:171","msg":"trace[1151025408] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"454.003033ms","start":"2026-04-20T04:52:01.878369Z","end":"2026-04-20T04:52:02.332372Z","steps":["trace[1151025408] 'process raft request' (duration: 453.671303ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:02.332552Z","caller":"traceutil/trace.go:171","msg":"trace[1945407901] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"447.411962ms","start":"2026-04-20T04:52:01.885102Z","end":"2026-04-20T04:52:02.332514Z","steps":["trace[1945407901] 'process raft request' (duration: 447.167906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:02.332654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:01.878339Z","time spent":"454.158666ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:52:02.332738Z","caller":"traceutil/trace.go:171","msg":"trace[186728201] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"258.139616ms","start":"2026-04-20T04:52:02.074573Z","end":"2026-04-20T04:52:02.332713Z","steps":["trace[186728201] 'process raft request' (duration: 258.010483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:02.332765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:01.885079Z","time spent":"447.597727ms","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:52:02.332803Z","caller":"traceutil/trace.go:171","msg":"trace[1040071999] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"399.672845ms","start":"2026-04-20T04:52:01.933096Z","end":"2026-04-20T04:52:02.332769Z","steps":["trace[1040071999] 'process raft request' (duration: 399.286095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:02.332978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:01.933069Z","time spent":"399.828809ms","remote":"127.0.0.1:49556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:52:03.389815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592276850755915,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T04:52:03.890697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592276850755915,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T04:52:04.109822Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.346626627s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T04:52:04.111594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.631458239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:52:04.111658Z","caller":"traceutil/trace.go:171","msg":"trace[1848685893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"1.631590962s","start":"2026-04-20T04:52:02.480048Z","end":"2026-04-20T04:52:04.111639Z","steps":["trace[1848685893] 'range keys from in-memory index tree' (duration: 1.631339526s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:04.111813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:02.48002Z","time spent":"1.631780137s","remote":"127.0.0.1:49338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T04:52:04.113838Z","caller":"traceutil/trace.go:171","msg":"trace[1431368667] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"1.224672711s","start":"2026-04-20T04:52:02.889132Z","end":"2026-04-20T04:52:04.113805Z","steps":["trace[1431368667] 'read index received' (duration: 1.220880018s)","trace[1431368667] 'applied index is now lower than readState.Index' (duration: 3.791333ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:52:04.113986Z","caller":"traceutil/trace.go:171","msg":"trace[35888197] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.352676901s","start":"2026-04-20T04:52:02.76123Z","end":"2026-04-20T04:52:04.113907Z","steps":["trace[35888197] 'process raft request' (duration: 1.350021729s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:04.114063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224921678s","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-20T04:52:04.114232Z","caller":"traceutil/trace.go:171","msg":"trace[1531037862] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"1.225120224s","start":"2026-04-20T04:52:02.889092Z","end":"2026-04-20T04:52:04.114212Z","steps":["trace[1531037862] 'agreement among raft nodes before linearized reading' (duration: 1.224861457s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:04.114267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:02.761186Z","time spent":"1.352944679s","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:52:04.114329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:02.889073Z","time spent":"1.225243536s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T04:52:05.297962Z","caller":"traceutil/trace.go:171","msg":"trace[996103723] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"167.813002ms","start":"2026-04-20T04:52:05.13012Z","end":"2026-04-20T04:52:05.297933Z","steps":["trace[996103723] 'process raft request' (duration: 166.758303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:09.238287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.645139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T04:52:09.23839Z","caller":"traceutil/trace.go:171","msg":"trace[1123846515] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:1; response_revision:719; }","duration":"127.834685ms","start":"2026-04-20T04:52:09.110538Z","end":"2026-04-20T04:52:09.238373Z","steps":["trace[1123846515] 'range keys from in-memory index tree' (duration: 127.507477ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:09.347305Z","caller":"traceutil/trace.go:171","msg":"trace[694269285] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"201.312883ms","start":"2026-04-20T04:52:09.14595Z","end":"2026-04-20T04:52:09.347263Z","steps":["trace[694269285] 'process raft request' (duration: 200.917462ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:09.571526Z","caller":"traceutil/trace.go:171","msg":"trace[1715714675] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"137.006654ms","start":"2026-04-20T04:52:09.434483Z","end":"2026-04-20T04:52:09.57149Z","steps":["trace[1715714675] 'process raft request' (duration: 136.697495ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:09.944153Z","caller":"traceutil/trace.go:171","msg":"trace[1923386699] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"284.365959ms","start":"2026-04-20T04:52:09.659754Z","end":"2026-04-20T04:52:09.94412Z","steps":["trace[1923386699] 'process raft request' (duration: 284.086452ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:09.949958Z","caller":"traceutil/trace.go:171","msg":"trace[784533505] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:750; }","duration":"237.364532ms","start":"2026-04-20T04:52:09.712555Z","end":"2026-04-20T04:52:09.94992Z","steps":["trace[784533505] 'read index received' (duration: 231.246896ms)","trace[784533505] 'applied index is now lower than readState.Index' (duration: 6.116046ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:52:09.950063Z","caller":"traceutil/trace.go:171","msg":"trace[1844944316] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"286.167269ms","start":"2026-04-20T04:52:09.663858Z","end":"2026-04-20T04:52:09.950025Z","steps":["trace[1844944316] 'process raft request' (duration: 285.771428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:09.95025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.685051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-04-20T04:52:09.950356Z","caller":"traceutil/trace.go:171","msg":"trace[32644041] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:727; }","duration":"237.881076ms","start":"2026-04-20T04:52:09.712444Z","end":"2026-04-20T04:52:09.950325Z","steps":["trace[32644041] 'agreement among raft nodes before linearized reading' (duration: 237.604279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:09.950469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.598563ms","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-20T04:52:09.950588Z","caller":"traceutil/trace.go:171","msg":"trace[1525806338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"221.787448ms","start":"2026-04-20T04:52:09.728779Z","end":"2026-04-20T04:52:09.950566Z","steps":["trace[1525806338] 'agreement among raft nodes before linearized reading' (duration: 221.497421ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:12.757417Z","caller":"traceutil/trace.go:171","msg":"trace[1995925272] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"241.581188ms","start":"2026-04-20T04:52:12.515782Z","end":"2026-04-20T04:52:12.757363Z","steps":["trace[1995925272] 'process raft request' (duration: 237.401844ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:12.797456Z","caller":"traceutil/trace.go:171","msg":"trace[1849574895] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"168.934804ms","start":"2026-04-20T04:52:12.628493Z","end":"2026-04-20T04:52:12.797428Z","steps":["trace[1849574895] 'process raft request' (duration: 168.78702ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:13.370342Z","caller":"traceutil/trace.go:171","msg":"trace[1727052062] transaction","detail":"{read_only:false; number_of_response:0; response_revision:751; }","duration":"145.085823ms","start":"2026-04-20T04:52:13.225221Z","end":"2026-04-20T04:52:13.370307Z","steps":["trace[1727052062] 'process raft request' (duration: 109.914888ms)","trace[1727052062] 'compare' (duration: 35.035552ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:52:13.395342Z","caller":"traceutil/trace.go:171","msg":"trace[1652820578] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"119.729795ms","start":"2026-04-20T04:52:13.275585Z","end":"2026-04-20T04:52:13.395314Z","steps":["trace[1652820578] 'process raft request' (duration: 119.440426ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:32.273708Z","caller":"traceutil/trace.go:171","msg":"trace[1798330171] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"131.791222ms","start":"2026-04-20T04:52:32.141893Z","end":"2026-04-20T04:52:32.273684Z","steps":["trace[1798330171] 'read index received' (duration: 131.615708ms)","trace[1798330171] 'applied index is now lower than readState.Index' (duration: 174.534µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:52:32.273869Z","caller":"traceutil/trace.go:171","msg":"trace[625933484] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"186.022346ms","start":"2026-04-20T04:52:32.08778Z","end":"2026-04-20T04:52:32.273802Z","steps":["trace[625933484] 'process raft request' (duration: 185.743439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:32.273915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.01171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T04:52:32.274098Z","caller":"traceutil/trace.go:171","msg":"trace[36716392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:909; }","duration":"132.253426ms","start":"2026-04-20T04:52:32.141831Z","end":"2026-04-20T04:52:32.274084Z","steps":["trace[36716392] 'agreement among raft nodes before linearized reading' (duration: 131.949737ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:32.418226Z","caller":"traceutil/trace.go:171","msg":"trace[1218425916] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"137.378275ms","start":"2026-04-20T04:52:32.280813Z","end":"2026-04-20T04:52:32.418191Z","steps":["trace[1218425916] 'process raft request' (duration: 137.117957ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:32.418528Z","caller":"traceutil/trace.go:171","msg":"trace[151937686] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"137.233661ms","start":"2026-04-20T04:52:32.281252Z","end":"2026-04-20T04:52:32.418486Z","steps":["trace[151937686] 'process raft request' (duration: 136.836579ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:32.41871Z","caller":"traceutil/trace.go:171","msg":"trace[2050604011] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:940; }","duration":"114.183974ms","start":"2026-04-20T04:52:32.304501Z","end":"2026-04-20T04:52:32.418685Z","steps":["trace[2050604011] 'read index received' (duration: 60.351281ms)","trace[2050604011] 'applied index is now lower than readState.Index' (duration: 53.831053ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:52:32.418781Z","caller":"traceutil/trace.go:171","msg":"trace[767703015] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"134.129776ms","start":"2026-04-20T04:52:32.284624Z","end":"2026-04-20T04:52:32.418754Z","steps":["trace[767703015] 'process raft request' (duration: 133.741216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:32.418931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.424831ms","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-04-20T04:52:32.41899Z","caller":"traceutil/trace.go:171","msg":"trace[1521763935] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:913; }","duration":"114.561334ms","start":"2026-04-20T04:52:32.304414Z","end":"2026-04-20T04:52:32.418975Z","steps":["trace[1521763935] 'agreement among raft nodes before linearized reading' (duration: 114.361118ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:44.473879Z","caller":"traceutil/trace.go:171","msg":"trace[72227787] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"246.228223ms","start":"2026-04-20T04:52:44.227625Z","end":"2026-04-20T04:52:44.473853Z","steps":["trace[72227787] 'process raft request' (duration: 245.989476ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:44.474063Z","caller":"traceutil/trace.go:171","msg":"trace[1905130172] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"244.370894ms","start":"2026-04-20T04:52:44.229652Z","end":"2026-04-20T04:52:44.474023Z","steps":["trace[1905130172] 'process raft request' (duration: 244.113467ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:44.718956Z","caller":"traceutil/trace.go:171","msg":"trace[539061916] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"107.294826ms","start":"2026-04-20T04:52:44.611549Z","end":"2026-04-20T04:52:44.718844Z","steps":["trace[539061916] 'process raft request' (duration: 104.354749ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:59.371686Z","caller":"traceutil/trace.go:171","msg":"trace[558907503] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"531.212851ms","start":"2026-04-20T04:52:58.840443Z","end":"2026-04-20T04:52:59.371655Z","steps":["trace[558907503] 'process raft request' (duration: 530.959285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:59.371898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:58.840411Z","time spent":"531.380105ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:52:59.382464Z","caller":"traceutil/trace.go:171","msg":"trace[1660598194] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1275; }","duration":"504.411736ms","start":"2026-04-20T04:52:58.878017Z","end":"2026-04-20T04:52:59.382429Z","steps":["trace[1660598194] 'read index received' (duration: 493.420231ms)","trace[1660598194] 'applied index is now lower than readState.Index' (duration: 10.989945ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:52:59.382608Z","caller":"traceutil/trace.go:171","msg":"trace[1035895426] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"526.270153ms","start":"2026-04-20T04:52:58.85624Z","end":"2026-04-20T04:52:59.382511Z","steps":["trace[1035895426] 'process raft request' (duration: 525.989685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:59.38282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:58.856205Z","time spent":"526.507039ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:52:59.383116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.032562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T04:52:59.38289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.851526ms","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-20T04:52:59.383236Z","caller":"traceutil/trace.go:171","msg":"trace[653083807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"505.219206ms","start":"2026-04-20T04:52:58.877999Z","end":"2026-04-20T04:52:59.383219Z","steps":["trace[653083807] 'agreement among raft nodes before linearized reading' (duration: 504.687882ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:59.383234Z","caller":"traceutil/trace.go:171","msg":"trace[291765800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"505.256067ms","start":"2026-04-20T04:52:58.877951Z","end":"2026-04-20T04:52:59.383207Z","steps":["trace[291765800] 'agreement among raft nodes before linearized reading' (duration: 504.659601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:59.383275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:58.877923Z","time spent":"505.341509ms","remote":"127.0.0.1:49632","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":"warn","ts":"2026-04-20T04:52:59.383006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.587408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T04:52:59.383299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:58.877923Z","time spent":"505.35867ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T04:52:59.383008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.483616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T04:52:59.383356Z","caller":"traceutil/trace.go:171","msg":"trace[154738615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1240; }","duration":"502.958117ms","start":"2026-04-20T04:52:58.880371Z","end":"2026-04-20T04:52:59.383329Z","steps":["trace[154738615] 'agreement among raft nodes before linearized reading' (duration: 502.437773ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:59.383544Z","caller":"traceutil/trace.go:171","msg":"trace[1886583942] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:1; response_revision:1240; }","duration":"270.03627ms","start":"2026-04-20T04:52:59.113462Z","end":"2026-04-20T04:52:59.383498Z","steps":["trace[1886583942] 'agreement among raft nodes before linearized reading' (duration: 269.362753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:59.383083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.652109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-20T04:52:59.383696Z","caller":"traceutil/trace.go:171","msg":"trace[1717324476] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1240; }","duration":"372.300625ms","start":"2026-04-20T04:52:59.011367Z","end":"2026-04-20T04:52:59.383668Z","steps":["trace[1717324476] 'agreement among raft nodes before linearized reading' (duration: 371.525234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:59.383713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:58.880364Z","time spent":"503.242324ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T04:52:59.383815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:52:59.011323Z","time spent":"372.44596ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-20T04:53:21.482862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.429346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:53:21.48296Z","caller":"traceutil/trace.go:171","msg":"trace[1358376009] linearizableReadLoop","detail":"{readStateIndex:1561; appliedIndex:1560; }","duration":"151.356981ms","start":"2026-04-20T04:53:21.33159Z","end":"2026-04-20T04:53:21.482947Z","steps":["trace[1358376009] 'read index received' (duration: 20.684558ms)","trace[1358376009] 'applied index is now lower than readState.Index' (duration: 130.671382ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:21.482978Z","caller":"traceutil/trace.go:171","msg":"trace[1955200144] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"221.119951ms","start":"2026-04-20T04:53:21.261834Z","end":"2026-04-20T04:53:21.482954Z","steps":["trace[1955200144] 'process raft request' (duration: 90.52858ms)","trace[1955200144] 'compare' (duration: 130.237101ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:21.483135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.551895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-9sndd\" ","response":"range_response_count:1 size:7093"} {"level":"info","ts":"2026-04-20T04:53:21.483164Z","caller":"traceutil/trace.go:171","msg":"trace[1398783422] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-9sndd; range_end:; response_count:1; response_revision:1513; }","duration":"151.622517ms","start":"2026-04-20T04:53:21.331531Z","end":"2026-04-20T04:53:21.483154Z","steps":["trace[1398783422] 'agreement among raft nodes before linearized reading' (duration: 151.473663ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:21.694766Z","caller":"traceutil/trace.go:171","msg":"trace[1457503470] linearizableReadLoop","detail":"{readStateIndex:1565; appliedIndex:1563; }","duration":"125.960891ms","start":"2026-04-20T04:53:21.568777Z","end":"2026-04-20T04:53:21.694738Z","steps":["trace[1457503470] 'read index received' (duration: 125.732545ms)","trace[1457503470] 'applied index is now lower than readState.Index' (duration: 227.216µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:21.694839Z","caller":"traceutil/trace.go:171","msg":"trace[151034206] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"194.93799ms","start":"2026-04-20T04:53:21.49983Z","end":"2026-04-20T04:53:21.694768Z","steps":["trace[151034206] 'process raft request' (duration: 194.666844ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:21.694835Z","caller":"traceutil/trace.go:171","msg":"trace[320029255] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"194.058698ms","start":"2026-04-20T04:53:21.500741Z","end":"2026-04-20T04:53:21.6948Z","steps":["trace[320029255] 'process raft request' (duration: 193.948115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:21.695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.587766ms","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-20T04:53:21.695046Z","caller":"traceutil/trace.go:171","msg":"trace[1863318186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1517; }","duration":"198.691389ms","start":"2026-04-20T04:53:21.496342Z","end":"2026-04-20T04:53:21.695034Z","steps":["trace[1863318186] 'agreement among raft nodes before linearized reading' (duration: 198.499203ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:21.695223Z","caller":"traceutil/trace.go:171","msg":"trace[670847805] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"121.871834ms","start":"2026-04-20T04:53:21.57334Z","end":"2026-04-20T04:53:21.695212Z","steps":["trace[670847805] 'process raft request' (duration: 121.840243ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:21.695365Z","caller":"traceutil/trace.go:171","msg":"trace[1581361277] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"122.670425ms","start":"2026-04-20T04:53:21.572689Z","end":"2026-04-20T04:53:21.695359Z","steps":["trace[1581361277] 'process raft request' (duration: 122.393898ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:29.99344Z","caller":"traceutil/trace.go:171","msg":"trace[1896650578] linearizableReadLoop","detail":"{readStateIndex:1766; appliedIndex:1765; }","duration":"251.555631ms","start":"2026-04-20T04:53:29.741851Z","end":"2026-04-20T04:53:29.993406Z","steps":["trace[1896650578] 'read index received' (duration: 251.257612ms)","trace[1896650578] 'applied index is now lower than readState.Index' (duration: 296.279µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:29.993517Z","caller":"traceutil/trace.go:171","msg":"trace[499208926] transaction","detail":"{read_only:false; response_revision:1700; number_of_response:1; }","duration":"251.686355ms","start":"2026-04-20T04:53:29.741784Z","end":"2026-04-20T04:53:29.99347Z","steps":["trace[499208926] 'process raft request' (duration: 251.367796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:29.993782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.903189ms","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-20T04:53:29.993882Z","caller":"traceutil/trace.go:171","msg":"trace[189384170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1700; }","duration":"252.047484ms","start":"2026-04-20T04:53:29.741812Z","end":"2026-04-20T04:53:29.993859Z","steps":["trace[189384170] 'agreement among raft nodes before linearized reading' (duration: 251.795178ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:30.07313Z","caller":"traceutil/trace.go:171","msg":"trace[353328327] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"231.85646ms","start":"2026-04-20T04:53:29.841215Z","end":"2026-04-20T04:53:30.073071Z","steps":["trace[353328327] 'process raft request' (duration: 219.129579ms)","trace[353328327] 'compare' (duration: 12.510885ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:30.07336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.194609ms","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-20T04:53:30.073421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.669618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T04:53:30.073437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.432399ms","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-20T04:53:30.073444Z","caller":"traceutil/trace.go:171","msg":"trace[1278881984] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1702; }","duration":"331.324962ms","start":"2026-04-20T04:53:29.742095Z","end":"2026-04-20T04:53:30.07342Z","steps":["trace[1278881984] 'agreement among raft nodes before linearized reading' (duration: 331.078266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:30.073426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.247918ms","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-20T04:53:30.073487Z","caller":"traceutil/trace.go:171","msg":"trace[1438607737] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com; range_end:; response_count:0; response_revision:1702; }","duration":"183.780341ms","start":"2026-04-20T04:53:29.889676Z","end":"2026-04-20T04:53:30.073456Z","steps":["trace[1438607737] 'agreement among raft nodes before linearized reading' (duration: 183.650198ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:30.073509Z","caller":"traceutil/trace.go:171","msg":"trace[1851599683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1702; }","duration":"329.394872ms","start":"2026-04-20T04:53:29.744106Z","end":"2026-04-20T04:53:30.073501Z","steps":["trace[1851599683] 'agreement among raft nodes before linearized reading' (duration: 329.155916ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:30.073503Z","caller":"traceutil/trace.go:171","msg":"trace[1216003483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1702; }","duration":"330.535732ms","start":"2026-04-20T04:53:29.74295Z","end":"2026-04-20T04:53:30.073486Z","steps":["trace[1216003483] 'agreement among raft nodes before linearized reading' (duration: 330.386668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:30.073509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:29.742074Z","time spent":"331.426245ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T04:53:30.073545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:29.742923Z","time spent":"330.613464ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T04:53:30.073557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:29.744098Z","time spent":"329.450523ms","remote":"127.0.0.1:49632","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-20T04:53:30.297919Z","caller":"traceutil/trace.go:171","msg":"trace[635668396] linearizableReadLoop","detail":"{readStateIndex:1773; appliedIndex:1772; }","duration":"122.531601ms","start":"2026-04-20T04:53:30.175348Z","end":"2026-04-20T04:53:30.29788Z","steps":["trace[635668396] 'read index received' (duration: 56.62069ms)","trace[635668396] 'applied index is now lower than readState.Index' (duration: 65.905151ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:30.297966Z","caller":"traceutil/trace.go:171","msg":"trace[1284036663] transaction","detail":"{read_only:false; response_revision:1707; number_of_response:1; }","duration":"123.25544ms","start":"2026-04-20T04:53:30.174674Z","end":"2026-04-20T04:53:30.297929Z","steps":["trace[1284036663] 'process raft request' (duration: 57.270097ms)","trace[1284036663] 'compare' (duration: 65.721415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:30.298151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.771277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterrestores.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:53:30.298198Z","caller":"traceutil/trace.go:171","msg":"trace[133840944] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterrestores.pxc.percona.com; range_end:; response_count:0; response_revision:1707; }","duration":"122.842319ms","start":"2026-04-20T04:53:30.17534Z","end":"2026-04-20T04:53:30.298182Z","steps":["trace[133840944] 'agreement among raft nodes before linearized reading' (duration: 122.660575ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:30.307553Z","caller":"traceutil/trace.go:171","msg":"trace[91865901] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"130.440757ms","start":"2026-04-20T04:53:30.177083Z","end":"2026-04-20T04:53:30.307524Z","steps":["trace[91865901] 'process raft request' (duration: 130.095758ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:35.460488Z","caller":"traceutil/trace.go:171","msg":"trace[1193132718] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"128.219788ms","start":"2026-04-20T04:53:35.332231Z","end":"2026-04-20T04:53:35.460451Z","steps":["trace[1193132718] 'process raft request' (duration: 127.92835ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:35.763907Z","caller":"traceutil/trace.go:171","msg":"trace[1960364125] linearizableReadLoop","detail":"{readStateIndex:1843; appliedIndex:1842; }","duration":"110.283813ms","start":"2026-04-20T04:53:35.653601Z","end":"2026-04-20T04:53:35.763884Z","steps":["trace[1960364125] 'read index received' (duration: 110.17785ms)","trace[1960364125] 'applied index is now lower than readState.Index' (duration: 104.793µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:35.764059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.443616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/percona-xtradb\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:53:35.764082Z","caller":"traceutil/trace.go:171","msg":"trace[749310559] range","detail":"{range_begin:/registry/secrets/openstack/percona-xtradb; range_end:; response_count:0; response_revision:1772; }","duration":"110.53778ms","start":"2026-04-20T04:53:35.653535Z","end":"2026-04-20T04:53:35.764073Z","steps":["trace[749310559] 'agreement among raft nodes before linearized reading' (duration: 110.439507ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:35.764116Z","caller":"traceutil/trace.go:171","msg":"trace[92703941] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"213.108263ms","start":"2026-04-20T04:53:35.550901Z","end":"2026-04-20T04:53:35.764009Z","steps":["trace[92703941] 'process raft request' (duration: 212.823326ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:35.887135Z","caller":"traceutil/trace.go:171","msg":"trace[1271962546] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"117.8598ms","start":"2026-04-20T04:53:35.769235Z","end":"2026-04-20T04:53:35.887095Z","steps":["trace[1271962546] 'process raft request' (duration: 68.617802ms)","trace[1271962546] 'compare' (duration: 49.013182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:44.402831Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592276850758409,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T04:53:44.714537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.631977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:53:44.714678Z","caller":"traceutil/trace.go:171","msg":"trace[1587855111] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"912.563742ms","start":"2026-04-20T04:53:43.802092Z","end":"2026-04-20T04:53:44.714656Z","steps":["trace[1587855111] 'process raft request' (duration: 738.720519ms)","trace[1587855111] 'compare' (duration: 173.433583ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:44.714743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:43.802063Z","time spent":"912.648893ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:53:44.884998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.298746ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T04:53:44.88511Z","caller":"traceutil/trace.go:171","msg":"trace[1540515092] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1960; }","duration":"983.240247ms","start":"2026-04-20T04:53:43.901851Z","end":"2026-04-20T04:53:44.885091Z","steps":["trace[1540515092] 'read index received' (duration: 638.9783ms)","trace[1540515092] 'applied index is now lower than readState.Index' (duration: 344.260357ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:44.88534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.484774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-20T04:53:44.885382Z","caller":"traceutil/trace.go:171","msg":"trace[1601507956] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1889; }","duration":"983.580956ms","start":"2026-04-20T04:53:43.901788Z","end":"2026-04-20T04:53:44.885369Z","steps":["trace[1601507956] 'agreement among raft nodes before linearized reading' (duration: 983.387891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:44.885431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:43.901759Z","time spent":"983.659678ms","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-20T04:53:44.885423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.412114ms","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-20T04:53:44.885498Z","caller":"traceutil/trace.go:171","msg":"trace[350305362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1889; }","duration":"705.523686ms","start":"2026-04-20T04:53:44.179955Z","end":"2026-04-20T04:53:44.885479Z","steps":["trace[350305362] 'agreement among raft nodes before linearized reading' (duration: 705.28498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:44.885551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.179907Z","time spent":"705.635318ms","remote":"127.0.0.1:49632","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-20T04:53:44.885539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.815951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T04:53:44.885683Z","caller":"traceutil/trace.go:171","msg":"trace[10149209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1889; }","duration":"791.115309ms","start":"2026-04-20T04:53:44.09454Z","end":"2026-04-20T04:53:44.885656Z","steps":["trace[10149209] 'agreement among raft nodes before linearized reading' (duration: 790.687608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:44.885751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.962753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-20T04:53:44.885752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.094508Z","time spent":"791.230842ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T04:53:44.885789Z","caller":"traceutil/trace.go:171","msg":"trace[1237118118] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1889; }","duration":"472.047085ms","start":"2026-04-20T04:53:44.413732Z","end":"2026-04-20T04:53:44.885779Z","steps":["trace[1237118118] 'agreement among raft nodes before linearized reading' (duration: 471.946943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:44.885802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.245135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T04:53:44.885831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.413705Z","time spent":"472.119617ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-20T04:53:44.88584Z","caller":"traceutil/trace.go:171","msg":"trace[1556437386] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1889; }","duration":"684.337937ms","start":"2026-04-20T04:53:44.201493Z","end":"2026-04-20T04:53:44.885831Z","steps":["trace[1556437386] 'agreement among raft nodes before linearized reading' (duration: 684.261236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:44.885888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.201474Z","time spent":"684.402539ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":15,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-20T04:53:44.885841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.088844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-20T04:53:44.886897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.269433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-20T04:53:44.886988Z","caller":"traceutil/trace.go:171","msg":"trace[1886897853] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1889; }","duration":"791.359065ms","start":"2026-04-20T04:53:44.095584Z","end":"2026-04-20T04:53:44.886943Z","steps":["trace[1886897853] 'agreement among raft nodes before linearized reading' (duration: 790.38503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:44.887032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.095574Z","time spent":"791.445048ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-20T04:53:44.88698Z","caller":"traceutil/trace.go:171","msg":"trace[2082957114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1889; }","duration":"741.224153ms","start":"2026-04-20T04:53:44.14568Z","end":"2026-04-20T04:53:44.886904Z","steps":["trace[2082957114] 'agreement among raft nodes before linearized reading' (duration: 739.967791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:44.887214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.145585Z","time spent":"741.565072ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T04:53:44.887233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.88706ms","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-04-20T04:53:44.887416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.491521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-decd18ab-9b04-46f3-8d2c-8a0bc407ab54\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T04:53:44.88744Z","caller":"traceutil/trace.go:171","msg":"trace[8513842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1889; }","duration":"715.075174ms","start":"2026-04-20T04:53:44.172261Z","end":"2026-04-20T04:53:44.887336Z","steps":["trace[8513842] 'agreement among raft nodes before linearized reading' (duration: 713.668978ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:44.887506Z","caller":"traceutil/trace.go:171","msg":"trace[1752655484] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-decd18ab-9b04-46f3-8d2c-8a0bc407ab54; range_end:; response_count:1; response_revision:1889; }","duration":"746.629744ms","start":"2026-04-20T04:53:44.14085Z","end":"2026-04-20T04:53:44.887479Z","steps":["trace[1752655484] 'agreement among raft nodes before linearized reading' (duration: 745.105465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:44.88756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.140827Z","time spent":"746.711225ms","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-decd18ab-9b04-46f3-8d2c-8a0bc407ab54\" "} {"level":"warn","ts":"2026-04-20T04:53:44.887579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.172236Z","time spent":"715.30842ms","remote":"127.0.0.1:49632","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-20T04:53:44.887692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.89396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T04:53:44.887811Z","caller":"traceutil/trace.go:171","msg":"trace[1827858417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1889; }","duration":"700.073526ms","start":"2026-04-20T04:53:44.187716Z","end":"2026-04-20T04:53:44.88779Z","steps":["trace[1827858417] 'agreement among raft nodes before linearized reading' (duration: 698.090754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:44.887985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.187693Z","time spent":"700.27224ms","remote":"127.0.0.1:49632","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-20T04:53:44.895061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.196908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-decd18ab-9b04-46f3-8d2c-8a0bc407ab54\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T04:53:44.895584Z","caller":"traceutil/trace.go:171","msg":"trace[824704739] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-decd18ab-9b04-46f3-8d2c-8a0bc407ab54; range_end:; response_count:1; response_revision:1889; }","duration":"442.645313ms","start":"2026-04-20T04:53:44.452904Z","end":"2026-04-20T04:53:44.895549Z","steps":["trace[824704739] 'agreement among raft nodes before linearized reading' (duration: 432.999842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:44.89566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.452878Z","time spent":"442.756285ms","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-decd18ab-9b04-46f3-8d2c-8a0bc407ab54\" "} {"level":"info","ts":"2026-04-20T04:53:45.030429Z","caller":"traceutil/trace.go:171","msg":"trace[1456269943] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"134.147428ms","start":"2026-04-20T04:53:44.896243Z","end":"2026-04-20T04:53:45.030391Z","steps":["trace[1456269943] 'process raft request' (duration: 133.682947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:45.500694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.486316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:53:45.50104Z","caller":"traceutil/trace.go:171","msg":"trace[843353645] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"603.762686ms","start":"2026-04-20T04:53:44.897252Z","end":"2026-04-20T04:53:45.501015Z","steps":["trace[843353645] 'process raft request' (duration: 603.602742ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:45.501027Z","caller":"traceutil/trace.go:171","msg":"trace[439212224] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"604.304651ms","start":"2026-04-20T04:53:44.896655Z","end":"2026-04-20T04:53:45.50096Z","steps":["trace[439212224] 'process raft request' (duration: 206.413084ms)","trace[439212224] 'compare' (duration: 397.299032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:45.501129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.896946Z","time spent":"604.146226ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:53:45.501253Z","caller":"traceutil/trace.go:171","msg":"trace[1909186822] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"603.785958ms","start":"2026-04-20T04:53:44.897437Z","end":"2026-04-20T04:53:45.501223Z","steps":["trace[1909186822] 'process raft request' (duration: 603.480879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:45.501387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.896636Z","time spent":"604.526426ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:53:45.501514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.897428Z","time spent":"603.945411ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:53:45.501666Z","caller":"traceutil/trace.go:171","msg":"trace[1029368319] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"603.694934ms","start":"2026-04-20T04:53:44.897914Z","end":"2026-04-20T04:53:45.501609Z","steps":["trace[1029368319] 'process raft request' (duration: 603.216242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:45.501868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.897896Z","time spent":"603.866239ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:53:45.50211Z","caller":"traceutil/trace.go:171","msg":"trace[816112850] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"604.125646ms","start":"2026-04-20T04:53:44.897966Z","end":"2026-04-20T04:53:45.502092Z","steps":["trace[816112850] 'process raft request' (duration: 603.595182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:45.502231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.897953Z","time spent":"604.222348ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:53:45.502202Z","caller":"traceutil/trace.go:171","msg":"trace[971063586] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"604.144506ms","start":"2026-04-20T04:53:44.898031Z","end":"2026-04-20T04:53:45.502176Z","steps":["trace[971063586] 'process raft request' (duration: 603.878159ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:45.502407Z","caller":"traceutil/trace.go:171","msg":"trace[747033640] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"601.181209ms","start":"2026-04-20T04:53:44.901205Z","end":"2026-04-20T04:53:45.502386Z","steps":["trace[747033640] 'process raft request' (duration: 600.903761ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:45.502516Z","caller":"traceutil/trace.go:171","msg":"trace[196370726] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"499.337011ms","start":"2026-04-20T04:53:45.003162Z","end":"2026-04-20T04:53:45.502499Z","steps":["trace[196370726] 'process raft request' (duration: 499.133335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:45.502528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.901178Z","time spent":"601.292272ms","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:53:45.502678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:45.003134Z","time spent":"499.497285ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:53:45.502909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:44.898026Z","time spent":"604.385422ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:53:45.5178Z","caller":"traceutil/trace.go:171","msg":"trace[94173516] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"414.829331ms","start":"2026-04-20T04:53:45.102953Z","end":"2026-04-20T04:53:45.517782Z","steps":["trace[94173516] 'process raft request' (duration: 414.734538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:45.517907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:45.102913Z","time spent":"414.939244ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:53:49.633099Z","caller":"traceutil/trace.go:171","msg":"trace[1917727494] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"137.129981ms","start":"2026-04-20T04:53:49.495931Z","end":"2026-04-20T04:53:49.633061Z","steps":["trace[1917727494] 'process raft request' (duration: 104.938444ms)","trace[1917727494] 'compare' (duration: 32.033462ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:49.886575Z","caller":"traceutil/trace.go:171","msg":"trace[1630213967] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"205.304552ms","start":"2026-04-20T04:53:49.681218Z","end":"2026-04-20T04:53:49.886523Z","steps":["trace[1630213967] 'process raft request' (duration: 205.081285ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:49.887248Z","caller":"traceutil/trace.go:171","msg":"trace[489771633] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"205.335143ms","start":"2026-04-20T04:53:49.681878Z","end":"2026-04-20T04:53:49.887213Z","steps":["trace[489771633] 'process raft request' (duration: 205.118566ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:49.887473Z","caller":"traceutil/trace.go:171","msg":"trace[1986439176] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"204.955173ms","start":"2026-04-20T04:53:49.682448Z","end":"2026-04-20T04:53:49.887403Z","steps":["trace[1986439176] 'process raft request' (duration: 204.705487ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:49.88751Z","caller":"traceutil/trace.go:171","msg":"trace[1216727749] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"206.193513ms","start":"2026-04-20T04:53:49.681257Z","end":"2026-04-20T04:53:49.88745Z","steps":["trace[1216727749] 'process raft request' (duration: 205.223899ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:54.898506Z","caller":"traceutil/trace.go:171","msg":"trace[1384005275] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"145.614519ms","start":"2026-04-20T04:53:54.752854Z","end":"2026-04-20T04:53:54.898469Z","steps":["trace[1384005275] 'process raft request' (duration: 58.650615ms)","trace[1384005275] 'compare' (duration: 86.818991ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:54:00.438251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592276850758689,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T04:54:00.777688Z","caller":"traceutil/trace.go:171","msg":"trace[730138063] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"841.033904ms","start":"2026-04-20T04:53:59.936628Z","end":"2026-04-20T04:54:00.777662Z","steps":["trace[730138063] 'process raft request' (duration: 840.86686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:00.777838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:59.936594Z","time spent":"841.164898ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:54:00.796362Z","caller":"traceutil/trace.go:171","msg":"trace[45278447] linearizableReadLoop","detail":"{readStateIndex:2099; appliedIndex:2097; }","duration":"858.497332ms","start":"2026-04-20T04:53:59.937838Z","end":"2026-04-20T04:54:00.796336Z","steps":["trace[45278447] 'read index received' (duration: 839.672309ms)","trace[45278447] 'applied index is now lower than readState.Index' (duration: 18.823773ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:00.796396Z","caller":"traceutil/trace.go:171","msg":"trace[91224276] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"858.479632ms","start":"2026-04-20T04:53:59.937884Z","end":"2026-04-20T04:54:00.796363Z","steps":["trace[91224276] 'process raft request' (duration: 858.193895ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:00.796462Z","caller":"traceutil/trace.go:171","msg":"trace[1925972036] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"266.076391ms","start":"2026-04-20T04:54:00.53035Z","end":"2026-04-20T04:54:00.796426Z","steps":["trace[1925972036] 'process raft request' (duration: 265.935788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:00.796615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.475675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T04:54:00.796634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.776889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-20T04:54:00.796598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:59.937853Z","time spent":"858.608365ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:54:00.79668Z","caller":"traceutil/trace.go:171","msg":"trace[550566543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2024; }","duration":"316.583909ms","start":"2026-04-20T04:54:00.48008Z","end":"2026-04-20T04:54:00.796664Z","steps":["trace[550566543] 'agreement among raft nodes before linearized reading' (duration: 316.485276ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:00.796684Z","caller":"traceutil/trace.go:171","msg":"trace[1806171915] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2024; }","duration":"858.849442ms","start":"2026-04-20T04:53:59.93782Z","end":"2026-04-20T04:54:00.796669Z","steps":["trace[1806171915] 'agreement among raft nodes before linearized reading' (duration: 858.652316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:00.796715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:00.480053Z","time spent":"316.654701ms","remote":"127.0.0.1:49338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T04:54:00.79673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:59.937785Z","time spent":"858.934344ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T04:54:00.796761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.581349ms","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-20T04:54:00.796851Z","caller":"traceutil/trace.go:171","msg":"trace[826665653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2024; }","duration":"857.733092ms","start":"2026-04-20T04:53:59.939105Z","end":"2026-04-20T04:54:00.796838Z","steps":["trace[826665653] 'agreement among raft nodes before linearized reading' (duration: 857.453754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:00.796747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.93698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-20T04:54:00.796851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.374887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-20T04:54:00.796919Z","caller":"traceutil/trace.go:171","msg":"trace[196594026] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2024; }","duration":"794.465529ms","start":"2026-04-20T04:54:00.002434Z","end":"2026-04-20T04:54:00.7969Z","steps":["trace[196594026] 'agreement among raft nodes before linearized reading' (duration: 794.185972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:00.796923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:59.939076Z","time spent":"857.806514ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T04:54:00.796939Z","caller":"traceutil/trace.go:171","msg":"trace[860340601] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2024; }","duration":"291.152436ms","start":"2026-04-20T04:54:00.505743Z","end":"2026-04-20T04:54:00.796895Z","steps":["trace[860340601] 'agreement among raft nodes before linearized reading' (duration: 290.829447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:00.796958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:00.002424Z","time spent":"794.52495ms","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-20T04:54:13.307038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.379022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:54:13.307101Z","caller":"traceutil/trace.go:171","msg":"trace[212429629] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2101; }","duration":"166.460654ms","start":"2026-04-20T04:54:13.140626Z","end":"2026-04-20T04:54:13.307087Z","steps":["trace[212429629] 'range keys from in-memory index tree' (duration: 166.307049ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:31.126533Z","caller":"traceutil/trace.go:171","msg":"trace[1070267993] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"151.878799ms","start":"2026-04-20T04:54:30.974614Z","end":"2026-04-20T04:54:31.126493Z","steps":["trace[1070267993] 'process raft request' (duration: 107.309085ms)","trace[1070267993] 'compare' (duration: 44.328938ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:31.126916Z","caller":"traceutil/trace.go:171","msg":"trace[2113789311] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"151.972102ms","start":"2026-04-20T04:54:30.974889Z","end":"2026-04-20T04:54:31.126862Z","steps":["trace[2113789311] 'process raft request' (duration: 151.547231ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:31.127016Z","caller":"traceutil/trace.go:171","msg":"trace[511265516] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"150.466763ms","start":"2026-04-20T04:54:30.976516Z","end":"2026-04-20T04:54:31.126983Z","steps":["trace[511265516] 'process raft request' (duration: 150.254917ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:31.636396Z","caller":"traceutil/trace.go:171","msg":"trace[975759080] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"139.979244ms","start":"2026-04-20T04:54:31.49639Z","end":"2026-04-20T04:54:31.63637Z","steps":["trace[975759080] 'process raft request' (duration: 139.8131ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:31.644448Z","caller":"traceutil/trace.go:171","msg":"trace[216017141] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"101.224129ms","start":"2026-04-20T04:54:31.543196Z","end":"2026-04-20T04:54:31.64442Z","steps":["trace[216017141] 'process raft request' (duration: 101.092336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:37.468578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.553384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-20T04:54:37.468668Z","caller":"traceutil/trace.go:171","msg":"trace[1937467374] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2225; }","duration":"303.702318ms","start":"2026-04-20T04:54:37.164942Z","end":"2026-04-20T04:54:37.468644Z","steps":["trace[1937467374] 'range keys from in-memory index tree' (duration: 303.332199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:37.468714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:37.164911Z","time spent":"303.79258ms","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-20T04:54:37.468569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.764271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T04:54:37.468982Z","caller":"traceutil/trace.go:171","msg":"trace[996680715] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2225; }","duration":"350.242682ms","start":"2026-04-20T04:54:37.118699Z","end":"2026-04-20T04:54:37.468941Z","steps":["trace[996680715] 'count revisions from in-memory index tree' (duration: 349.588985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:37.469069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.216356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-20T04:54:37.469111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:37.11867Z","time spent":"350.388357ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":54,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-04-20T04:54:37.469159Z","caller":"traceutil/trace.go:171","msg":"trace[607229753] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2225; }","duration":"319.37479ms","start":"2026-04-20T04:54:37.149762Z","end":"2026-04-20T04:54:37.469137Z","steps":["trace[607229753] 'range keys from in-memory index tree' (duration: 318.977289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:37.469213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:37.149738Z","time spent":"319.465152ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T04:54:37.469254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.617246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T04:54:37.469315Z","caller":"traceutil/trace.go:171","msg":"trace[471771680] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2225; }","duration":"319.699029ms","start":"2026-04-20T04:54:37.149601Z","end":"2026-04-20T04:54:37.4693Z","steps":["trace[471771680] 'range keys from in-memory index tree' (duration: 319.467953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:37.46935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:37.149596Z","time spent":"319.7461ms","remote":"127.0.0.1:49632","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-20T04:54:37.469519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.253642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T04:54:37.469557Z","caller":"traceutil/trace.go:171","msg":"trace[936526586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2225; }","duration":"320.328774ms","start":"2026-04-20T04:54:37.149217Z","end":"2026-04-20T04:54:37.469546Z","steps":["trace[936526586] 'range keys from in-memory index tree' (duration: 320.121849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:37.469586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:37.149197Z","time spent":"320.381425ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T04:54:37.469769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.966012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:54:37.4699Z","caller":"traceutil/trace.go:171","msg":"trace[1898952907] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"249.992528ms","start":"2026-04-20T04:54:37.219888Z","end":"2026-04-20T04:54:37.469881Z","steps":["trace[1898952907] 'process raft request' (duration: 141.79887ms)","trace[1898952907] 'compare' (duration: 107.531701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:54:38.000189Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592276850759216,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T04:54:38.03537Z","caller":"traceutil/trace.go:171","msg":"trace[168559052] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"561.379233ms","start":"2026-04-20T04:54:37.473944Z","end":"2026-04-20T04:54:38.035323Z","steps":["trace[168559052] 'process raft request' (duration: 561.230269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:38.035581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:37.473919Z","time spent":"561.581669ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:54:38.063389Z","caller":"traceutil/trace.go:171","msg":"trace[1657770202] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"589.348362ms","start":"2026-04-20T04:54:37.473987Z","end":"2026-04-20T04:54:38.063335Z","steps":["trace[1657770202] 'process raft request' (duration: 589.049343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:38.063682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:37.473964Z","time spent":"589.519476ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:54:38.063694Z","caller":"traceutil/trace.go:171","msg":"trace[1123919021] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"156.9732ms","start":"2026-04-20T04:54:37.906693Z","end":"2026-04-20T04:54:38.063667Z","steps":["trace[1123919021] 'process raft request' (duration: 156.588709ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:38.0634Z","caller":"traceutil/trace.go:171","msg":"trace[1061347340] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"588.660175ms","start":"2026-04-20T04:54:37.474715Z","end":"2026-04-20T04:54:38.063375Z","steps":["trace[1061347340] 'process raft request' (duration: 588.50768ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:38.063866Z","caller":"traceutil/trace.go:171","msg":"trace[2094738779] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"147.510006ms","start":"2026-04-20T04:54:37.916338Z","end":"2026-04-20T04:54:38.063848Z","steps":["trace[2094738779] 'process raft request' (duration: 147.229679ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:38.063884Z","caller":"traceutil/trace.go:171","msg":"trace[33959603] linearizableReadLoop","detail":"{readStateIndex:2315; appliedIndex:2310; }","duration":"564.476563ms","start":"2026-04-20T04:54:37.499367Z","end":"2026-04-20T04:54:38.063843Z","steps":["trace[33959603] 'read index received' (duration: 535.838638ms)","trace[33959603] 'applied index is now lower than readState.Index' (duration: 28.635575ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:54:38.064079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.690209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T04:54:38.06397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:37.474704Z","time spent":"589.129326ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:54:38.064128Z","caller":"traceutil/trace.go:171","msg":"trace[1083856019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2231; }","duration":"564.769691ms","start":"2026-04-20T04:54:37.499344Z","end":"2026-04-20T04:54:38.064114Z","steps":["trace[1083856019] 'agreement among raft nodes before linearized reading' (duration: 564.600195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:38.064168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:37.499336Z","time spent":"564.824663ms","remote":"127.0.0.1:49338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T04:54:38.064421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.701821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a7f7a303c8698c\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-20T04:54:38.064484Z","caller":"traceutil/trace.go:171","msg":"trace[2129198198] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a7f7a303c8698c; range_end:; response_count:1; response_revision:2231; }","duration":"522.761222ms","start":"2026-04-20T04:54:37.541705Z","end":"2026-04-20T04:54:38.064466Z","steps":["trace[2129198198] 'agreement among raft nodes before linearized reading' (duration: 522.551007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:38.064528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:37.541629Z","time spent":"522.890995ms","remote":"127.0.0.1:49412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a7f7a303c8698c\" "} {"level":"warn","ts":"2026-04-20T04:54:38.064714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.319358ms","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-20T04:54:38.065083Z","caller":"traceutil/trace.go:171","msg":"trace[1666904752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2231; }","duration":"400.688297ms","start":"2026-04-20T04:54:37.66433Z","end":"2026-04-20T04:54:38.065019Z","steps":["trace[1666904752] 'agreement among raft nodes before linearized reading' (duration: 399.986449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:38.065182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:37.664301Z","time spent":"400.863702ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T04:55:27.24537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.966828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:55:27.245465Z","caller":"traceutil/trace.go:171","msg":"trace[1259490613] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2668; }","duration":"674.129272ms","start":"2026-04-20T04:55:26.571316Z","end":"2026-04-20T04:55:27.245445Z","steps":["trace[1259490613] 'count revisions from in-memory index tree' (duration: 673.852505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:27.245503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:55:26.571293Z","time spent":"674.197934ms","remote":"127.0.0.1:41274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-04-20T04:55:33.52975Z","caller":"traceutil/trace.go:171","msg":"trace[961523608] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"107.497469ms","start":"2026-04-20T04:55:33.422229Z","end":"2026-04-20T04:55:33.529726Z","steps":["trace[961523608] 'process raft request' (duration: 79.600066ms)","trace[961523608] 'compare' (duration: 27.329418ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:34.164595Z","caller":"traceutil/trace.go:171","msg":"trace[310498978] transaction","detail":"{read_only:false; response_revision:2714; number_of_response:1; }","duration":"163.466188ms","start":"2026-04-20T04:55:34.001111Z","end":"2026-04-20T04:55:34.164577Z","steps":["trace[310498978] 'process raft request' (duration: 163.373175ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:34.456123Z","caller":"traceutil/trace.go:171","msg":"trace[2119547810] transaction","detail":"{read_only:false; response_revision:2719; number_of_response:1; }","duration":"107.332815ms","start":"2026-04-20T04:55:34.348751Z","end":"2026-04-20T04:55:34.456084Z","steps":["trace[2119547810] 'process raft request' (duration: 81.099564ms)","trace[2119547810] 'compare' (duration: 26.060417ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:34.462641Z","caller":"traceutil/trace.go:171","msg":"trace[1450385863] transaction","detail":"{read_only:false; response_revision:2721; number_of_response:1; }","duration":"112.175096ms","start":"2026-04-20T04:55:34.350434Z","end":"2026-04-20T04:55:34.462609Z","steps":["trace[1450385863] 'process raft request' (duration: 112.089494ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:34.463005Z","caller":"traceutil/trace.go:171","msg":"trace[1211940435] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"113.234443ms","start":"2026-04-20T04:55:34.349489Z","end":"2026-04-20T04:55:34.462724Z","steps":["trace[1211940435] 'process raft request' (duration: 112.876464ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:00.77625Z","caller":"traceutil/trace.go:171","msg":"trace[1284068426] transaction","detail":"{read_only:false; response_revision:2872; number_of_response:1; }","duration":"178.956175ms","start":"2026-04-20T04:56:00.597256Z","end":"2026-04-20T04:56:00.776212Z","steps":["trace[1284068426] 'process raft request' (duration: 178.722649ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:00.891561Z","caller":"traceutil/trace.go:171","msg":"trace[540933560] transaction","detail":"{read_only:false; response_revision:2874; number_of_response:1; }","duration":"293.164624ms","start":"2026-04-20T04:56:00.598382Z","end":"2026-04-20T04:56:00.891547Z","steps":["trace[540933560] 'process raft request' (duration: 292.953238ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:00.891466Z","caller":"traceutil/trace.go:171","msg":"trace[1081777835] transaction","detail":"{read_only:false; response_revision:2873; number_of_response:1; }","duration":"293.211664ms","start":"2026-04-20T04:56:00.598198Z","end":"2026-04-20T04:56:00.89141Z","steps":["trace[1081777835] 'process raft request' (duration: 264.750122ms)","trace[1081777835] 'compare' (duration: 28.222046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:56:01.299916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.589669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:56:01.30007Z","caller":"traceutil/trace.go:171","msg":"trace[248831132] transaction","detail":"{read_only:false; response_revision:2875; number_of_response:1; }","duration":"372.448664ms","start":"2026-04-20T04:56:00.927596Z","end":"2026-04-20T04:56:01.300045Z","steps":["trace[248831132] 'process raft request' (duration: 250.581768ms)","trace[248831132] 'compare' (duration: 121.235669ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:56:01.300142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:56:00.927583Z","time spent":"372.521615ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:56:01.305719Z","caller":"traceutil/trace.go:171","msg":"trace[223924947] transaction","detail":"{read_only:false; response_revision:2876; number_of_response:1; }","duration":"194.093564ms","start":"2026-04-20T04:56:01.111605Z","end":"2026-04-20T04:56:01.305698Z","steps":["trace[223924947] 'process raft request' (duration: 193.983651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:01.773329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.95444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T04:56:01.773353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.694081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18a7f7b68a8e6a7a\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-04-20T04:56:01.773421Z","caller":"traceutil/trace.go:171","msg":"trace[218916911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2876; }","duration":"295.100752ms","start":"2026-04-20T04:56:01.478301Z","end":"2026-04-20T04:56:01.773402Z","steps":["trace[218916911] 'range keys from in-memory index tree' (duration: 294.859197ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:01.77344Z","caller":"traceutil/trace.go:171","msg":"trace[216611981] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18a7f7b68a8e6a7a; range_end:; response_count:1; response_revision:2876; }","duration":"367.840055ms","start":"2026-04-20T04:56:01.405578Z","end":"2026-04-20T04:56:01.773418Z","steps":["trace[216611981] 'range keys from in-memory index tree' (duration: 367.452705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:01.773491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:56:01.405556Z","time spent":"367.926016ms","remote":"127.0.0.1:49412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":788,"request content":"key:\"/registry/events/openstack/valkey-node-0.18a7f7b68a8e6a7a\" "} {"level":"warn","ts":"2026-04-20T04:56:01.773766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.307261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-20T04:56:01.773906Z","caller":"traceutil/trace.go:171","msg":"trace[1385281388] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2876; }","duration":"360.493256ms","start":"2026-04-20T04:56:01.413382Z","end":"2026-04-20T04:56:01.773875Z","steps":["trace[1385281388] 'range keys from in-memory index tree' (duration: 359.979412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:01.774034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:56:01.413375Z","time spent":"360.644809ms","remote":"127.0.0.1:49542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-20T04:56:02.205489Z","caller":"traceutil/trace.go:171","msg":"trace[396886313] linearizableReadLoop","detail":"{readStateIndex:2985; appliedIndex:2984; }","duration":"427.075999ms","start":"2026-04-20T04:56:01.778379Z","end":"2026-04-20T04:56:02.205455Z","steps":["trace[396886313] 'read index received' (duration: 426.801912ms)","trace[396886313] 'applied index is now lower than readState.Index' (duration: 272.527µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:56:02.205562Z","caller":"traceutil/trace.go:171","msg":"trace[1063829249] transaction","detail":"{read_only:false; response_revision:2877; number_of_response:1; }","duration":"429.354229ms","start":"2026-04-20T04:56:01.776165Z","end":"2026-04-20T04:56:02.205519Z","steps":["trace[1063829249] 'process raft request' (duration: 429.03471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:02.205832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.413728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1449"} {"level":"info","ts":"2026-04-20T04:56:02.205919Z","caller":"traceutil/trace.go:171","msg":"trace[628457976] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2877; }","duration":"427.552722ms","start":"2026-04-20T04:56:01.778343Z","end":"2026-04-20T04:56:02.205896Z","steps":["trace[628457976] 'agreement among raft nodes before linearized reading' (duration: 427.246834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:02.205972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:56:01.778329Z","time spent":"427.628403ms","remote":"127.0.0.1:49542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1472,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"warn","ts":"2026-04-20T04:56:02.206037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:56:01.776138Z","time spent":"429.673246ms","remote":"127.0.0.1:49412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:56:02.208516Z","caller":"traceutil/trace.go:171","msg":"trace[1171306802] transaction","detail":"{read_only:false; response_revision:2879; number_of_response:1; }","duration":"423.651811ms","start":"2026-04-20T04:56:01.784847Z","end":"2026-04-20T04:56:02.208499Z","steps":["trace[1171306802] 'process raft request' (duration: 423.59209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:02.208669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:56:01.784814Z","time spent":"423.752563ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:56:02.208699Z","caller":"traceutil/trace.go:171","msg":"trace[1877871872] transaction","detail":"{read_only:false; response_revision:2878; number_of_response:1; }","duration":"430.241611ms","start":"2026-04-20T04:56:01.778422Z","end":"2026-04-20T04:56:02.208664Z","steps":["trace[1877871872] 'process raft request' (duration: 429.871621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:02.208952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:56:01.778389Z","time spent":"430.438716ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:58:27.68222Z","caller":"traceutil/trace.go:171","msg":"trace[1288516383] transaction","detail":"{read_only:false; response_revision:3847; number_of_response:1; }","duration":"154.770354ms","start":"2026-04-20T04:58:27.527384Z","end":"2026-04-20T04:58:27.682154Z","steps":["trace[1288516383] 'process raft request' (duration: 154.553958ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.450591Z","caller":"traceutil/trace.go:171","msg":"trace[654855029] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4370; }","duration":"107.970724ms","start":"2026-04-20T04:59:41.342537Z","end":"2026-04-20T04:59:41.450508Z","steps":["trace[654855029] 'process raft request' (duration: 102.024034ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.452215Z","caller":"traceutil/trace.go:171","msg":"trace[818171304] transaction","detail":"{read_only:false; response_revision:4374; number_of_response:1; }","duration":"102.453415ms","start":"2026-04-20T04:59:41.349741Z","end":"2026-04-20T04:59:41.452195Z","steps":["trace[818171304] 'process raft request' (duration: 102.338232ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.452209Z","caller":"traceutil/trace.go:171","msg":"trace[767770314] transaction","detail":"{read_only:false; response_revision:4371; number_of_response:1; }","duration":"102.775063ms","start":"2026-04-20T04:59:41.349398Z","end":"2026-04-20T04:59:41.452173Z","steps":["trace[767770314] 'process raft request' (duration: 102.514786ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.452444Z","caller":"traceutil/trace.go:171","msg":"trace[1165453743] transaction","detail":"{read_only:false; response_revision:4375; number_of_response:1; }","duration":"102.612529ms","start":"2026-04-20T04:59:41.349775Z","end":"2026-04-20T04:59:41.452387Z","steps":["trace[1165453743] 'process raft request' (duration: 102.332032ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.452489Z","caller":"traceutil/trace.go:171","msg":"trace[250348425] transaction","detail":"{read_only:false; response_revision:4376; number_of_response:1; }","duration":"101.614072ms","start":"2026-04-20T04:59:41.350853Z","end":"2026-04-20T04:59:41.452467Z","steps":["trace[250348425] 'process raft request' (duration: 101.284023ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.452153Z","caller":"traceutil/trace.go:171","msg":"trace[898195506] transaction","detail":"{read_only:false; response_revision:4372; number_of_response:1; }","duration":"102.667981ms","start":"2026-04-20T04:59:41.349465Z","end":"2026-04-20T04:59:41.452133Z","steps":["trace[898195506] 'process raft request' (duration: 102.556529ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.452742Z","caller":"traceutil/trace.go:171","msg":"trace[1099455171] transaction","detail":"{read_only:false; response_revision:4377; number_of_response:1; }","duration":"101.648055ms","start":"2026-04-20T04:59:41.351079Z","end":"2026-04-20T04:59:41.452727Z","steps":["trace[1099455171] 'process raft request' (duration: 101.12318ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.452987Z","caller":"traceutil/trace.go:171","msg":"trace[1382034794] transaction","detail":"{read_only:false; response_revision:4378; number_of_response:1; }","duration":"101.819928ms","start":"2026-04-20T04:59:41.351151Z","end":"2026-04-20T04:59:41.452971Z","steps":["trace[1382034794] 'process raft request' (duration: 101.594472ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.452542Z","caller":"traceutil/trace.go:171","msg":"trace[825310201] transaction","detail":"{read_only:false; response_revision:4373; number_of_response:1; }","duration":"102.668641ms","start":"2026-04-20T04:59:41.349691Z","end":"2026-04-20T04:59:41.45236Z","steps":["trace[825310201] 'process raft request' (duration: 102.364013ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.453032Z","caller":"traceutil/trace.go:171","msg":"trace[45919623] transaction","detail":"{read_only:false; response_revision:4379; number_of_response:1; }","duration":"101.862549ms","start":"2026-04-20T04:59:41.351159Z","end":"2026-04-20T04:59:41.453021Z","steps":["trace[45919623] 'process raft request' (duration: 101.704545ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.453137Z","caller":"traceutil/trace.go:171","msg":"trace[1682298102] transaction","detail":"{read_only:false; response_revision:4380; number_of_response:1; }","duration":"101.566911ms","start":"2026-04-20T04:59:41.351549Z","end":"2026-04-20T04:59:41.453116Z","steps":["trace[1682298102] 'process raft request' (duration: 101.368265ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.45328Z","caller":"traceutil/trace.go:171","msg":"trace[808348064] transaction","detail":"{read_only:false; response_revision:4381; number_of_response:1; }","duration":"101.577311ms","start":"2026-04-20T04:59:41.351689Z","end":"2026-04-20T04:59:41.453266Z","steps":["trace[808348064] 'process raft request' (duration: 101.282614ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.45396Z","caller":"traceutil/trace.go:171","msg":"trace[1425640358] transaction","detail":"{read_only:false; response_revision:4385; number_of_response:1; }","duration":"100.302367ms","start":"2026-04-20T04:59:41.353638Z","end":"2026-04-20T04:59:41.453941Z","steps":["trace[1425640358] 'process raft request' (duration: 99.802214ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:41.453786Z","caller":"traceutil/trace.go:171","msg":"trace[2093189364] transaction","detail":"{read_only:false; response_revision:4383; number_of_response:1; }","duration":"100.196304ms","start":"2026-04-20T04:59:41.353537Z","end":"2026-04-20T04:59:41.453733Z","steps":["trace[2093189364] 'process raft request' (duration: 99.814815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:59:49.535828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.926829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-20T04:59:49.535952Z","caller":"traceutil/trace.go:171","msg":"trace[1885237115] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4823; }","duration":"129.123174ms","start":"2026-04-20T04:59:49.406805Z","end":"2026-04-20T04:59:49.535928Z","steps":["trace[1885237115] 'range keys from in-memory index tree' (duration: 128.716623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:59:51.53215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.622282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-20T04:59:51.532244Z","caller":"traceutil/trace.go:171","msg":"trace[39989091] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4867; }","duration":"191.763395ms","start":"2026-04-20T04:59:51.34046Z","end":"2026-04-20T04:59:51.532224Z","steps":["trace[39989091] 'range keys from in-memory index tree' (duration: 191.446977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:59:53.412907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.910594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b38cd47d-0854-41b3-9572-8da9c9f43011\" ","response":"range_response_count:1 size:4079"} {"level":"info","ts":"2026-04-20T04:59:53.413016Z","caller":"traceutil/trace.go:171","msg":"trace[137618802] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b38cd47d-0854-41b3-9572-8da9c9f43011; range_end:; response_count:1; response_revision:4985; }","duration":"114.088458ms","start":"2026-04-20T04:59:53.298904Z","end":"2026-04-20T04:59:53.412993Z","steps":["trace[137618802] 'range keys from in-memory index tree' (duration: 113.692037ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:53.530602Z","caller":"traceutil/trace.go:171","msg":"trace[556787334] linearizableReadLoop","detail":"{readStateIndex:5173; appliedIndex:5172; }","duration":"199.877884ms","start":"2026-04-20T04:59:53.330703Z","end":"2026-04-20T04:59:53.53058Z","steps":["trace[556787334] 'read index received' (duration: 199.623517ms)","trace[556787334] 'applied index is now lower than readState.Index' (duration: 253.327µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:59:53.530847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.128201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-qmrl9\" ","response":"range_response_count:1 size:9830"} {"level":"info","ts":"2026-04-20T04:59:53.530893Z","caller":"traceutil/trace.go:171","msg":"trace[929373551] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-qmrl9; range_end:; response_count:1; response_revision:4986; }","duration":"200.221473ms","start":"2026-04-20T04:59:53.330658Z","end":"2026-04-20T04:59:53.53088Z","steps":["trace[929373551] 'agreement among raft nodes before linearized reading' (duration: 200.050569ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:53.530721Z","caller":"traceutil/trace.go:171","msg":"trace[1083733372] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"210.910791ms","start":"2026-04-20T04:59:53.319768Z","end":"2026-04-20T04:59:53.530679Z","steps":["trace[1083733372] 'process raft request' (duration: 210.641714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:59:53.531003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.866104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-qmrl9.18a7f7f0f60139f0\" ","response":"range_response_count:1 size:962"} {"level":"warn","ts":"2026-04-20T04:59:53.531007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.647617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b38cd47d-0854-41b3-9572-8da9c9f43011\" ","response":"range_response_count:1 size:4079"} {"level":"info","ts":"2026-04-20T04:59:53.53109Z","caller":"traceutil/trace.go:171","msg":"trace[1323635767] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-qmrl9.18a7f7f0f60139f0; range_end:; response_count:1; response_revision:4986; }","duration":"200.002388ms","start":"2026-04-20T04:59:53.331061Z","end":"2026-04-20T04:59:53.531063Z","steps":["trace[1323635767] 'agreement among raft nodes before linearized reading' (duration: 199.69875ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:53.531147Z","caller":"traceutil/trace.go:171","msg":"trace[123334573] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b38cd47d-0854-41b3-9572-8da9c9f43011; range_end:; response_count:1; response_revision:4986; }","duration":"113.839871ms","start":"2026-04-20T04:59:53.417282Z","end":"2026-04-20T04:59:53.531121Z","steps":["trace[123334573] 'agreement among raft nodes before linearized reading' (duration: 113.516173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:59:57.650228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.169492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:59:57.650365Z","caller":"traceutil/trace.go:171","msg":"trace[2083900046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5083; }","duration":"170.357457ms","start":"2026-04-20T04:59:57.479976Z","end":"2026-04-20T04:59:57.650334Z","steps":["trace[2083900046] 'range keys from in-memory index tree' (duration: 170.068029ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:57.65063Z","caller":"traceutil/trace.go:171","msg":"trace[1838996216] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"107.644114ms","start":"2026-04-20T04:59:57.542939Z","end":"2026-04-20T04:59:57.650583Z","steps":["trace[1838996216] 'process raft request' (duration: 97.028598ms)","trace[1838996216] 'compare' (duration: 10.136934ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:59:59.766755Z","caller":"traceutil/trace.go:171","msg":"trace[443361834] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"103.496643ms","start":"2026-04-20T04:59:59.663209Z","end":"2026-04-20T04:59:59.766705Z","steps":["trace[443361834] 'process raft request' (duration: 74.826899ms)","trace[443361834] 'compare' (duration: 28.386536ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:59:59.979961Z","caller":"traceutil/trace.go:171","msg":"trace[604919630] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"129.609047ms","start":"2026-04-20T04:59:59.850309Z","end":"2026-04-20T04:59:59.979918Z","steps":["trace[604919630] 'process raft request' (duration: 36.047703ms)","trace[604919630] 'compare' (duration: 93.351939ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:00.17277Z","caller":"traceutil/trace.go:171","msg":"trace[260006573] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"186.265576ms","start":"2026-04-20T04:59:59.986474Z","end":"2026-04-20T05:00:00.172739Z","steps":["trace[260006573] 'process raft request' (duration: 186.085382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:04.143665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.221894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:00:04.143918Z","caller":"traceutil/trace.go:171","msg":"trace[1247176642] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"343.000506ms","start":"2026-04-20T05:00:03.800878Z","end":"2026-04-20T05:00:04.143878Z","steps":["trace[1247176642] 'process raft request' (duration: 215.466745ms)","trace[1247176642] 'compare' (duration: 127.010537ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:04.143955Z","caller":"traceutil/trace.go:171","msg":"trace[574014631] linearizableReadLoop","detail":"{readStateIndex:5383; appliedIndex:5382; }","duration":"268.764733ms","start":"2026-04-20T05:00:03.875145Z","end":"2026-04-20T05:00:04.14391Z","steps":["trace[574014631] 'read index received' (duration: 141.232961ms)","trace[574014631] 'applied index is now lower than readState.Index' (duration: 127.528262ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:04.14413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:03.800851Z","time spent":"343.170031ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:00:04.144347Z","caller":"traceutil/trace.go:171","msg":"trace[1776150098] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"264.156669ms","start":"2026-04-20T05:00:03.88017Z","end":"2026-04-20T05:00:04.144326Z","steps":["trace[1776150098] 'process raft request' (duration: 263.626965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:04.144373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.182816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-04-20T05:00:04.144374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.000978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-20T05:00:04.144425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.980737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T05:00:04.144474Z","caller":"traceutil/trace.go:171","msg":"trace[340046498] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5190; }","duration":"149.311199ms","start":"2026-04-20T05:00:03.995139Z","end":"2026-04-20T05:00:04.14445Z","steps":["trace[340046498] 'agreement among raft nodes before linearized reading' (duration: 149.043572ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:04.144478Z","caller":"traceutil/trace.go:171","msg":"trace[1662991907] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5190; }","duration":"286.178333ms","start":"2026-04-20T05:00:03.858282Z","end":"2026-04-20T05:00:04.14446Z","steps":["trace[1662991907] 'agreement among raft nodes before linearized reading' (duration: 285.868765ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:04.144503Z","caller":"traceutil/trace.go:171","msg":"trace[2112383379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5190; }","duration":"150.12271ms","start":"2026-04-20T05:00:03.994357Z","end":"2026-04-20T05:00:04.14448Z","steps":["trace[2112383379] 'agreement among raft nodes before linearized reading' (duration: 149.812872ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:05.378951Z","caller":"traceutil/trace.go:171","msg":"trace[51033153] linearizableReadLoop","detail":"{readStateIndex:5413; appliedIndex:5412; }","duration":"115.583448ms","start":"2026-04-20T05:00:05.263345Z","end":"2026-04-20T05:00:05.378928Z","steps":["trace[51033153] 'read index received' (duration: 46.254827ms)","trace[51033153] 'applied index is now lower than readState.Index' (duration: 69.327631ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:05.378998Z","caller":"traceutil/trace.go:171","msg":"trace[99398845] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"134.20527ms","start":"2026-04-20T05:00:05.244762Z","end":"2026-04-20T05:00:05.378967Z","steps":["trace[99398845] 'process raft request' (duration: 64.969112ms)","trace[99398845] 'compare' (duration: 69.079454ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:05.379493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.129644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:10 size:380254"} {"level":"info","ts":"2026-04-20T05:00:05.379603Z","caller":"traceutil/trace.go:171","msg":"trace[1850483335] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:10; response_revision:5217; }","duration":"116.302068ms","start":"2026-04-20T05:00:05.263277Z","end":"2026-04-20T05:00:05.379579Z","steps":["trace[1850483335] 'agreement among raft nodes before linearized reading' (duration: 115.787614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:11.762424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.403449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-04-20T05:00:11.762514Z","caller":"traceutil/trace.go:171","msg":"trace[1552223463] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5283; }","duration":"101.902849ms","start":"2026-04-20T05:00:11.660589Z","end":"2026-04-20T05:00:11.762492Z","steps":["trace[1552223463] 'agreement among raft nodes before linearized reading' (duration: 100.276716ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:12.098409Z","caller":"traceutil/trace.go:171","msg":"trace[1539760246] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"130.198324ms","start":"2026-04-20T05:00:11.968181Z","end":"2026-04-20T05:00:12.098379Z","steps":["trace[1539760246] 'process raft request' (duration: 130.018009ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:15.87707Z","caller":"traceutil/trace.go:171","msg":"trace[262201826] transaction","detail":"{read_only:false; response_revision:5374; number_of_response:1; }","duration":"102.725578ms","start":"2026-04-20T05:00:15.77432Z","end":"2026-04-20T05:00:15.877045Z","steps":["trace[262201826] 'process raft request' (duration: 102.553252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:33.220434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.139816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-20T05:00:33.220496Z","caller":"traceutil/trace.go:171","msg":"trace[1703763418] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5610; }","duration":"117.25017ms","start":"2026-04-20T05:00:33.103233Z","end":"2026-04-20T05:00:33.220483Z","steps":["trace[1703763418] 'range keys from in-memory index tree' (duration: 116.998672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:34.241967Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017321813s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T05:00:34.24246Z","caller":"traceutil/trace.go:171","msg":"trace[1269468976] transaction","detail":"{read_only:false; response_revision:5611; number_of_response:1; }","duration":"1.017874587s","start":"2026-04-20T05:00:33.224552Z","end":"2026-04-20T05:00:34.242427Z","steps":["trace[1269468976] 'process raft request' (duration: 1.017667651s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:34.242709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:33.22453Z","time spent":"1.018075893s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:00:35.628549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.731673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:00:35.628707Z","caller":"traceutil/trace.go:171","msg":"trace[2085839294] transaction","detail":"{read_only:false; response_revision:5612; number_of_response:1; }","duration":"2.149349156s","start":"2026-04-20T05:00:33.479342Z","end":"2026-04-20T05:00:35.628692Z","steps":["trace[2085839294] 'process raft request' (duration: 1.588394697s)","trace[2085839294] 'compare' (duration: 560.651611ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.628762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:33.479317Z","time spent":"2.149417888s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:00:35.629012Z","caller":"traceutil/trace.go:171","msg":"trace[1540708100] transaction","detail":"{read_only:false; response_revision:5613; number_of_response:1; }","duration":"1.689837801s","start":"2026-04-20T05:00:33.939149Z","end":"2026-04-20T05:00:35.628986Z","steps":["trace[1540708100] 'process raft request' (duration: 1.689496412s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:35.629139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:33.939119Z","time spent":"1.689962054s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:00:35.629661Z","caller":"traceutil/trace.go:171","msg":"trace[374395199] transaction","detail":"{read_only:false; response_revision:5614; number_of_response:1; }","duration":"1.572413137s","start":"2026-04-20T05:00:34.057205Z","end":"2026-04-20T05:00:35.629618Z","steps":["trace[374395199] 'process raft request' (duration: 1.571682998s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:35.630035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:34.057179Z","time spent":"1.572775646s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:00:35.630099Z","caller":"traceutil/trace.go:171","msg":"trace[67323448] linearizableReadLoop","detail":"{readStateIndex:5816; appliedIndex:5814; }","duration":"1.843192487s","start":"2026-04-20T05:00:33.786873Z","end":"2026-04-20T05:00:35.630065Z","steps":["trace[67323448] 'read index received' (duration: 455.313596ms)","trace[67323448] 'applied index is now lower than readState.Index' (duration: 1.387870981s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.742045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.000101ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-20T05:00:35.742287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.955392883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-p9trw\" ","response":"range_response_count:1 size:8064"} {"level":"info","ts":"2026-04-20T05:00:35.742387Z","caller":"traceutil/trace.go:171","msg":"trace[1295449127] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-p9trw; range_end:; response_count:1; response_revision:5614; }","duration":"1.955544748s","start":"2026-04-20T05:00:33.786817Z","end":"2026-04-20T05:00:35.742361Z","steps":["trace[1295449127] 'agreement among raft nodes before linearized reading' (duration: 1.843388754s)","trace[1295449127] 'range keys from in-memory index tree' (duration: 111.908057ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.742442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:33.786798Z","time spent":"1.95563491s","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":8087,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-p9trw\" "} {"level":"info","ts":"2026-04-20T05:00:35.742691Z","caller":"traceutil/trace.go:171","msg":"trace[670544196] linearizableReadLoop","detail":"{readStateIndex:5820; appliedIndex:5818; }","duration":"112.504835ms","start":"2026-04-20T05:00:35.630157Z","end":"2026-04-20T05:00:35.742662Z","steps":["trace[670544196] 'read index received' (duration: 63.313µs)","trace[670544196] 'applied index is now lower than readState.Index' (duration: 112.437662ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:35.74282Z","caller":"traceutil/trace.go:171","msg":"trace[643896012] transaction","detail":"{read_only:false; response_revision:5615; number_of_response:1; }","duration":"793.527565ms","start":"2026-04-20T05:00:34.949281Z","end":"2026-04-20T05:00:35.742809Z","steps":["trace[643896012] 'process raft request' (duration: 792.873438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:35.743094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:34.949252Z","time spent":"793.593097ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:00:35.84001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.44295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:00:35.840125Z","caller":"traceutil/trace.go:171","msg":"trace[1142513075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5615; }","duration":"118.602764ms","start":"2026-04-20T05:00:35.721491Z","end":"2026-04-20T05:00:35.840094Z","steps":["trace[1142513075] 'agreement among raft nodes before linearized reading' (duration: 21.416573ms)","trace[1142513075] 'range keys from in-memory index tree' (duration: 97.010846ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.840216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075623053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T05:00:35.840264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154112115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8jnph\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-04-20T05:00:35.840282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.020314838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-04-20T05:00:35.84035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161108768s","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-20T05:00:35.840428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.033687879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:00:35.840437Z","caller":"traceutil/trace.go:171","msg":"trace[1977442766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5615; }","duration":"1.161247472s","start":"2026-04-20T05:00:34.679168Z","end":"2026-04-20T05:00:35.840416Z","steps":["trace[1977442766] 'agreement among raft nodes before linearized reading' (duration: 1.06369367s)","trace[1977442766] 'range keys from in-memory index tree' (duration: 97.352757ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:35.840469Z","caller":"traceutil/trace.go:171","msg":"trace[1654574249] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:5615; }","duration":"2.033749821s","start":"2026-04-20T05:00:33.806709Z","end":"2026-04-20T05:00:35.840459Z","steps":["trace[1654574249] 'agreement among raft nodes before linearized reading' (duration: 1.93621172s)","trace[1654574249] 'count revisions from in-memory index tree' (duration: 97.481239ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.840494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.545075129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-20T05:00:35.84052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:33.806699Z","time spent":"2.033805132s","remote":"127.0.0.1:49468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-20T05:00:35.840522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161823167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-20T05:00:35.840367Z","caller":"traceutil/trace.go:171","msg":"trace[1158403773] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8jnph; range_end:; response_count:1; response_revision:5615; }","duration":"1.154243368s","start":"2026-04-20T05:00:34.6861Z","end":"2026-04-20T05:00:35.840343Z","steps":["trace[1158403773] 'agreement among raft nodes before linearized reading' (duration: 1.056782398s)","trace[1158403773] 'range keys from in-memory index tree' (duration: 97.214653ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.840547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:34.679148Z","time spent":"1.161386535s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T05:00:35.840574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532953551s","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-20T05:00:35.840613Z","caller":"traceutil/trace.go:171","msg":"trace[1395537846] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5615; }","duration":"1.545161142s","start":"2026-04-20T05:00:34.295371Z","end":"2026-04-20T05:00:35.840532Z","steps":["trace[1395537846] 'agreement among raft nodes before linearized reading' (duration: 1.44756352s)","trace[1395537846] 'range keys from in-memory index tree' (duration: 97.480849ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:35.840619Z","caller":"traceutil/trace.go:171","msg":"trace[374406299] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5615; }","duration":"1.16196172s","start":"2026-04-20T05:00:34.678635Z","end":"2026-04-20T05:00:35.840597Z","steps":["trace[374406299] 'agreement among raft nodes before linearized reading' (duration: 1.064240024s)","trace[374406299] 'range keys from in-memory index tree' (duration: 97.488071ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.840603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161241672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-20T05:00:35.840627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:34.686095Z","time spent":"1.154516475s","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-8jnph\" "} {"level":"info","ts":"2026-04-20T05:00:35.84145Z","caller":"traceutil/trace.go:171","msg":"trace[2104021328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5615; }","duration":"1.162101304s","start":"2026-04-20T05:00:34.67928Z","end":"2026-04-20T05:00:35.841381Z","steps":["trace[2104021328] 'agreement among raft nodes before linearized reading' (duration: 1.063574367s)","trace[2104021328] 'range keys from in-memory index tree' (duration: 97.331895ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.840682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:34.678614Z","time spent":"1.162058662s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-20T05:00:35.840376Z","caller":"traceutil/trace.go:171","msg":"trace[985764177] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5615; }","duration":"2.020462212s","start":"2026-04-20T05:00:33.819891Z","end":"2026-04-20T05:00:35.840354Z","steps":["trace[985764177] 'agreement among raft nodes before linearized reading' (duration: 1.922995152s)","trace[985764177] 'range keys from in-memory index tree' (duration: 97.221782ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.841678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:33.819871Z","time spent":"2.021784997s","remote":"127.0.0.1:49496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-20T05:00:35.840309Z","caller":"traceutil/trace.go:171","msg":"trace[1376111187] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:5615; }","duration":"1.075784127s","start":"2026-04-20T05:00:34.764503Z","end":"2026-04-20T05:00:35.840287Z","steps":["trace[1376111187] 'agreement among raft nodes before linearized reading' (duration: 978.346778ms)","trace[1376111187] 'count revisions from in-memory index tree' (duration: 97.291004ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.842074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:34.764478Z","time spent":"1.077576845s","remote":"127.0.0.1:49712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":99,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-04-20T05:00:35.840668Z","caller":"traceutil/trace.go:171","msg":"trace[1665621720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5615; }","duration":"1.533082765s","start":"2026-04-20T05:00:34.307544Z","end":"2026-04-20T05:00:35.840626Z","steps":["trace[1665621720] 'agreement among raft nodes before linearized reading' (duration: 1.4353747s)","trace[1665621720] 'range keys from in-memory index tree' (duration: 97.574712ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.842146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:34.30751Z","time spent":"1.534618855s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T05:00:35.84069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:34.29535Z","time spent":"1.545321896s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T05:00:35.842182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:34.679276Z","time spent":"1.162267908s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T05:00:35.840799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537213572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T05:00:35.842384Z","caller":"traceutil/trace.go:171","msg":"trace[378157374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5615; }","duration":"1.538806675s","start":"2026-04-20T05:00:34.303543Z","end":"2026-04-20T05:00:35.842349Z","steps":["trace[378157374] 'agreement among raft nodes before linearized reading' (duration: 1.439388914s)","trace[378157374] 'range keys from in-memory index tree' (duration: 97.216701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.842457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:34.303532Z","time spent":"1.538908407s","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T05:00:35.840487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110245702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-20T05:00:35.84252Z","caller":"traceutil/trace.go:171","msg":"trace[175847737] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5615; }","duration":"1.112301827s","start":"2026-04-20T05:00:34.730201Z","end":"2026-04-20T05:00:35.842503Z","steps":["trace[175847737] 'agreement among raft nodes before linearized reading' (duration: 1.01267099s)","trace[175847737] 'range keys from in-memory index tree' (duration: 97.250794ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.842568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:34.730184Z","time spent":"1.112364068s","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T05:00:35.840808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360683727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:00:35.84263Z","caller":"traceutil/trace.go:171","msg":"trace[1801077389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5615; }","duration":"1.362554636s","start":"2026-04-20T05:00:34.480059Z","end":"2026-04-20T05:00:35.842614Z","steps":["trace[1801077389] 'agreement among raft nodes before linearized reading' (duration: 1.262768406s)","trace[1801077389] 'range keys from in-memory index tree' (duration: 97.932642ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:35.842681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:34.480013Z","time spent":"1.3626549s","remote":"127.0.0.1:49338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T05:00:36.084685Z","caller":"traceutil/trace.go:171","msg":"trace[943594747] linearizableReadLoop","detail":"{readStateIndex:5834; appliedIndex:5833; }","duration":"148.856839ms","start":"2026-04-20T05:00:35.935799Z","end":"2026-04-20T05:00:36.084656Z","steps":["trace[943594747] 'read index received' (duration: 95.513737ms)","trace[943594747] 'applied index is now lower than readState.Index' (duration: 53.341972ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:36.08491Z","caller":"traceutil/trace.go:171","msg":"trace[388401218] transaction","detail":"{read_only:false; response_revision:5629; number_of_response:1; }","duration":"149.511986ms","start":"2026-04-20T05:00:35.935348Z","end":"2026-04-20T05:00:36.08486Z","steps":["trace[388401218] 'process raft request' (duration: 96.022722ms)","trace[388401218] 'compare' (duration: 53.078353ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:36.085155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.233748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-20T05:00:36.085262Z","caller":"traceutil/trace.go:171","msg":"trace[828818177] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5629; }","duration":"149.466555ms","start":"2026-04-20T05:00:35.935777Z","end":"2026-04-20T05:00:36.085243Z","steps":["trace[828818177] 'agreement among raft nodes before linearized reading' (duration: 148.983953ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:05.859284Z","caller":"traceutil/trace.go:171","msg":"trace[1180594107] transaction","detail":"{read_only:false; response_revision:5917; number_of_response:1; }","duration":"125.146856ms","start":"2026-04-20T05:01:05.734115Z","end":"2026-04-20T05:01:05.859261Z","steps":["trace[1180594107] 'process raft request' (duration: 124.975501ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:06.139948Z","caller":"traceutil/trace.go:171","msg":"trace[1749340916] linearizableReadLoop","detail":"{readStateIndex:6132; appliedIndex:6130; }","duration":"295.886799ms","start":"2026-04-20T05:01:05.84403Z","end":"2026-04-20T05:01:06.139917Z","steps":["trace[1749340916] 'read index received' (duration: 15.576169ms)","trace[1749340916] 'applied index is now lower than readState.Index' (duration: 280.30967ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:01:06.140044Z","caller":"traceutil/trace.go:171","msg":"trace[1501718610] transaction","detail":"{read_only:false; response_revision:5919; number_of_response:1; }","duration":"404.477571ms","start":"2026-04-20T05:01:05.735543Z","end":"2026-04-20T05:01:06.140021Z","steps":["trace[1501718610] 'process raft request' (duration: 404.263385ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:06.140055Z","caller":"traceutil/trace.go:171","msg":"trace[180916143] transaction","detail":"{read_only:false; response_revision:5918; number_of_response:1; }","duration":"404.778048ms","start":"2026-04-20T05:01:05.735255Z","end":"2026-04-20T05:01:06.140033Z","steps":["trace[180916143] 'process raft request' (duration: 402.773775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:06.140161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:05.735535Z","time spent":"404.565393ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:01:06.140172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:01:05.735227Z","time spent":"404.879001ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:01:06.140222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.904693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-20T05:01:06.140243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.204908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:01:06.140271Z","caller":"traceutil/trace.go:171","msg":"trace[1558998658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5919; }","duration":"131.998876ms","start":"2026-04-20T05:01:06.008258Z","end":"2026-04-20T05:01:06.140257Z","steps":["trace[1558998658] 'agreement among raft nodes before linearized reading' (duration: 131.836451ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:06.140299Z","caller":"traceutil/trace.go:171","msg":"trace[741622227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5919; }","duration":"296.31165ms","start":"2026-04-20T05:01:05.843975Z","end":"2026-04-20T05:01:06.140286Z","steps":["trace[741622227] 'agreement among raft nodes before linearized reading' (duration: 296.216688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:06.140454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.821719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8jnph\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T05:01:06.140482Z","caller":"traceutil/trace.go:171","msg":"trace[1950986927] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8jnph; range_end:; response_count:1; response_revision:5919; }","duration":"126.884422ms","start":"2026-04-20T05:01:06.013589Z","end":"2026-04-20T05:01:06.140473Z","steps":["trace[1950986927] 'agreement among raft nodes before linearized reading' (duration: 126.778339ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:16.049635Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2953} {"level":"info","ts":"2026-04-20T05:01:16.127173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2953,"took":"75.711888ms","hash":2987798859,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":33353728,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-20T05:01:16.127854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2987798859,"revision":2953,"compact-revision":-1} {"level":"warn","ts":"2026-04-20T05:03:21.813519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.218016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:16 size:141871"} {"level":"info","ts":"2026-04-20T05:03:21.813636Z","caller":"traceutil/trace.go:171","msg":"trace[1665722238] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:16; response_revision:6776; }","duration":"462.37854ms","start":"2026-04-20T05:03:21.351236Z","end":"2026-04-20T05:03:21.813615Z","steps":["trace[1665722238] 'range keys from in-memory index tree' (duration: 461.925569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:03:21.81368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:03:21.351222Z","time spent":"462.446682ms","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":16,"response size":141894,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-04-20T05:03:37.021444Z","caller":"traceutil/trace.go:171","msg":"trace[65650801] transaction","detail":"{read_only:false; response_revision:6882; number_of_response:1; }","duration":"100.438068ms","start":"2026-04-20T05:03:36.920977Z","end":"2026-04-20T05:03:37.021415Z","steps":["trace[65650801] 'process raft request' (duration: 100.330885ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:03:37.021544Z","caller":"traceutil/trace.go:171","msg":"trace[1873067418] transaction","detail":"{read_only:false; response_revision:6881; number_of_response:1; }","duration":"100.550821ms","start":"2026-04-20T05:03:36.920933Z","end":"2026-04-20T05:03:37.021483Z","steps":["trace[1873067418] 'process raft request' (duration: 90.337055ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:03:37.02171Z","caller":"traceutil/trace.go:171","msg":"trace[1194700244] transaction","detail":"{read_only:false; response_revision:6883; number_of_response:1; }","duration":"100.238762ms","start":"2026-04-20T05:03:36.921437Z","end":"2026-04-20T05:03:37.021675Z","steps":["trace[1194700244] 'process raft request' (duration: 99.934865ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:03:37.02175Z","caller":"traceutil/trace.go:171","msg":"trace[1138894211] transaction","detail":"{read_only:false; response_revision:6884; number_of_response:1; }","duration":"100.188541ms","start":"2026-04-20T05:03:36.921506Z","end":"2026-04-20T05:03:37.021694Z","steps":["trace[1138894211] 'process raft request' (duration: 99.971636ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:06:16.055941Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5975} {"level":"info","ts":"2026-04-20T05:06:16.150672Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5975,"took":"92.676596ms","hash":3332773893,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":21733376,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-20T05:06:16.150729Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3332773893,"revision":5975,"compact-revision":2953} {"level":"info","ts":"2026-04-20T05:06:20.163345Z","caller":"traceutil/trace.go:171","msg":"trace[1857939786] linearizableReadLoop","detail":"{readStateIndex:8543; appliedIndex:8542; }","duration":"258.218841ms","start":"2026-04-20T05:06:19.905092Z","end":"2026-04-20T05:06:20.163311Z","steps":["trace[1857939786] 'read index received' (duration: 257.981535ms)","trace[1857939786] 'applied index is now lower than readState.Index' (duration: 236.356µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:06:20.163438Z","caller":"traceutil/trace.go:171","msg":"trace[307566675] transaction","detail":"{read_only:false; response_revision:8255; number_of_response:1; }","duration":"276.865565ms","start":"2026-04-20T05:06:19.886509Z","end":"2026-04-20T05:06:20.163374Z","steps":["trace[307566675] 'process raft request' (duration: 276.628139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:20.16365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.4977ms","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-20T05:06:20.163733Z","caller":"traceutil/trace.go:171","msg":"trace[1282687826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8255; }","duration":"258.659823ms","start":"2026-04-20T05:06:19.905052Z","end":"2026-04-20T05:06:20.163711Z","steps":["trace[1282687826] 'agreement among raft nodes before linearized reading' (duration: 258.420956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:20.16377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.144206ms","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-20T05:06:20.163777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.219008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-04-20T05:06:20.163843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.659282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T05:06:20.163858Z","caller":"traceutil/trace.go:171","msg":"trace[985721842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8255; }","duration":"175.294179ms","start":"2026-04-20T05:06:19.988543Z","end":"2026-04-20T05:06:20.163837Z","steps":["trace[985721842] 'agreement among raft nodes before linearized reading' (duration: 175.061713ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:06:20.163899Z","caller":"traceutil/trace.go:171","msg":"trace[1627632016] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8255; }","duration":"244.365651ms","start":"2026-04-20T05:06:19.919495Z","end":"2026-04-20T05:06:20.163861Z","steps":["trace[1627632016] 'agreement among raft nodes before linearized reading' (duration: 244.048834ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:06:20.163919Z","caller":"traceutil/trace.go:171","msg":"trace[1033490185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8255; }","duration":"241.770544ms","start":"2026-04-20T05:06:19.922129Z","end":"2026-04-20T05:06:20.1639Z","steps":["trace[1033490185] 'agreement among raft nodes before linearized reading' (duration: 241.484467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:20.164912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.069259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T05:06:20.166665Z","caller":"traceutil/trace.go:171","msg":"trace[1267430156] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8255; }","duration":"157.866517ms","start":"2026-04-20T05:06:20.008765Z","end":"2026-04-20T05:06:20.166631Z","steps":["trace[1267430156] 'agreement among raft nodes before linearized reading' (duration: 154.870799ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:06:21.623879Z","caller":"traceutil/trace.go:171","msg":"trace[585716303] linearizableReadLoop","detail":"{readStateIndex:8549; appliedIndex:8548; }","duration":"348.509264ms","start":"2026-04-20T05:06:21.275345Z","end":"2026-04-20T05:06:21.623855Z","steps":["trace[585716303] 'read index received' (duration: 348.375031ms)","trace[585716303] 'applied index is now lower than readState.Index' (duration: 133.013µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:06:21.62398Z","caller":"traceutil/trace.go:171","msg":"trace[987612418] transaction","detail":"{read_only:false; response_revision:8261; number_of_response:1; }","duration":"622.948557ms","start":"2026-04-20T05:06:21.000972Z","end":"2026-04-20T05:06:21.62392Z","steps":["trace[987612418] 'process raft request' (duration: 622.727011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:21.624088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.69072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:06:21.624159Z","caller":"traceutil/trace.go:171","msg":"trace[677514087] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:8261; }","duration":"348.850403ms","start":"2026-04-20T05:06:21.275292Z","end":"2026-04-20T05:06:21.624143Z","steps":["trace[677514087] 'agreement among raft nodes before linearized reading' (duration: 348.661558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:21.624177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:21.000941Z","time spent":"623.141032ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:06:21.624209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:21.275272Z","time spent":"348.925985ms","remote":"127.0.0.1:58356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-04-20T05:06:22.136116Z","caller":"traceutil/trace.go:171","msg":"trace[844404205] transaction","detail":"{read_only:false; response_revision:8262; number_of_response:1; }","duration":"808.932643ms","start":"2026-04-20T05:06:21.327159Z","end":"2026-04-20T05:06:22.136091Z","steps":["trace[844404205] 'process raft request' (duration: 808.751179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:22.13627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:21.327134Z","time spent":"809.054126ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:06:22.136312Z","caller":"traceutil/trace.go:171","msg":"trace[220793422] linearizableReadLoop","detail":"{readStateIndex:8551; appliedIndex:8549; }","duration":"335.821005ms","start":"2026-04-20T05:06:21.800447Z","end":"2026-04-20T05:06:22.136268Z","steps":["trace[220793422] 'read index received' (duration: 335.575179ms)","trace[220793422] 'applied index is now lower than readState.Index' (duration: 242.986µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:06:22.136511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.062302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:06:22.136564Z","caller":"traceutil/trace.go:171","msg":"trace[72978798] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:8263; }","duration":"336.157564ms","start":"2026-04-20T05:06:21.800392Z","end":"2026-04-20T05:06:22.13655Z","steps":["trace[72978798] 'agreement among raft nodes before linearized reading' (duration: 336.049422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:22.136591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:21.800366Z","time spent":"336.217306ms","remote":"127.0.0.1:41274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-04-20T05:06:22.136427Z","caller":"traceutil/trace.go:171","msg":"trace[1097807849] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"808.376569ms","start":"2026-04-20T05:06:21.327956Z","end":"2026-04-20T05:06:22.136332Z","steps":["trace[1097807849] 'process raft request' (duration: 808.092752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:22.137403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:21.327938Z","time spent":"809.432496ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:06:33.83387Z","caller":"traceutil/trace.go:171","msg":"trace[1550247873] transaction","detail":"{read_only:false; response_revision:8326; number_of_response:1; }","duration":"139.165212ms","start":"2026-04-20T05:06:33.694677Z","end":"2026-04-20T05:06:33.833842Z","steps":["trace[1550247873] 'process raft request' (duration: 138.997028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:34.381488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.880192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:06:34.381599Z","caller":"traceutil/trace.go:171","msg":"trace[1950043971] linearizableReadLoop","detail":"{readStateIndex:8618; appliedIndex:8617; }","duration":"140.456625ms","start":"2026-04-20T05:06:34.241126Z","end":"2026-04-20T05:06:34.381583Z","steps":["trace[1950043971] 'read index received' (duration: 73.222µs)","trace[1950043971] 'applied index is now lower than readState.Index' (duration: 140.381783ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:06:34.381755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.64196ms","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-20T05:06:34.381694Z","caller":"traceutil/trace.go:171","msg":"trace[326541715] transaction","detail":"{read_only:false; response_revision:8328; number_of_response:1; }","duration":"188.347158ms","start":"2026-04-20T05:06:34.193295Z","end":"2026-04-20T05:06:34.381642Z","steps":["trace[326541715] 'process raft request' (duration: 46.23474ms)","trace[326541715] 'compare' (duration: 141.76867ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:06:34.381785Z","caller":"traceutil/trace.go:171","msg":"trace[226011495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8328; }","duration":"140.672321ms","start":"2026-04-20T05:06:34.241105Z","end":"2026-04-20T05:06:34.381777Z","steps":["trace[226011495] 'agreement among raft nodes before linearized reading' (duration: 140.557557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:34.382027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.659322ms","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-20T05:06:34.382057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.829986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T05:06:34.382104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.580614ms","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-20T05:06:34.382189Z","caller":"traceutil/trace.go:171","msg":"trace[1772880303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8328; }","duration":"109.705427ms","start":"2026-04-20T05:06:34.272463Z","end":"2026-04-20T05:06:34.382168Z","steps":["trace[1772880303] 'agreement among raft nodes before linearized reading' (duration: 109.42067ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:06:34.382184Z","caller":"traceutil/trace.go:171","msg":"trace[1600744217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8328; }","duration":"121.043632ms","start":"2026-04-20T05:06:34.261112Z","end":"2026-04-20T05:06:34.382156Z","steps":["trace[1600744217] 'agreement among raft nodes before linearized reading' (duration: 120.660123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:34.382057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.349432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-20T05:06:34.382241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.604594ms","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-20T05:06:34.382293Z","caller":"traceutil/trace.go:171","msg":"trace[617101730] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8328; }","duration":"108.610859ms","start":"2026-04-20T05:06:34.273652Z","end":"2026-04-20T05:06:34.382263Z","steps":["trace[617101730] 'agreement among raft nodes before linearized reading' (duration: 108.223929ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:06:34.382095Z","caller":"traceutil/trace.go:171","msg":"trace[2140217436] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8328; }","duration":"110.764384ms","start":"2026-04-20T05:06:34.271315Z","end":"2026-04-20T05:06:34.38208Z","steps":["trace[2140217436] 'agreement among raft nodes before linearized reading' (duration: 110.59133ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:06:34.382332Z","caller":"traceutil/trace.go:171","msg":"trace[1849144334] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8328; }","duration":"109.793859ms","start":"2026-04-20T05:06:34.272525Z","end":"2026-04-20T05:06:34.382319Z","steps":["trace[1849144334] 'agreement among raft nodes before linearized reading' (duration: 109.272806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:34.812614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.982327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:06:34.812791Z","caller":"traceutil/trace.go:171","msg":"trace[1338326604] transaction","detail":"{read_only:false; response_revision:8329; number_of_response:1; }","duration":"426.379785ms","start":"2026-04-20T05:06:34.38638Z","end":"2026-04-20T05:06:34.81276Z","steps":["trace[1338326604] 'process raft request' (duration: 119.109451ms)","trace[1338326604] 'compare' (duration: 306.833274ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:06:34.812884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:34.386347Z","time spent":"426.491518ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:06:34.821706Z","caller":"traceutil/trace.go:171","msg":"trace[409509304] transaction","detail":"{read_only:false; response_revision:8331; number_of_response:1; }","duration":"433.281965ms","start":"2026-04-20T05:06:34.388391Z","end":"2026-04-20T05:06:34.821673Z","steps":["trace[409509304] 'process raft request' (duration: 433.165372ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:06:34.821734Z","caller":"traceutil/trace.go:171","msg":"trace[162868342] transaction","detail":"{read_only:false; response_revision:8330; number_of_response:1; }","duration":"434.752753ms","start":"2026-04-20T05:06:34.386951Z","end":"2026-04-20T05:06:34.821704Z","steps":["trace[162868342] 'process raft request' (duration: 434.440265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:34.821931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:34.386922Z","time spent":"434.895567ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:06:34.821942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:34.388375Z","time spent":"433.436419ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:06:34.821978Z","caller":"traceutil/trace.go:171","msg":"trace[1839268525] transaction","detail":"{read_only:false; response_revision:8332; number_of_response:1; }","duration":"433.46781ms","start":"2026-04-20T05:06:34.388485Z","end":"2026-04-20T05:06:34.821953Z","steps":["trace[1839268525] 'process raft request' (duration: 433.138172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:34.822117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:34.388476Z","time spent":"433.575843ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:06:34.822253Z","caller":"traceutil/trace.go:171","msg":"trace[751282523] transaction","detail":"{read_only:false; response_revision:8334; number_of_response:1; }","duration":"433.654715ms","start":"2026-04-20T05:06:34.388589Z","end":"2026-04-20T05:06:34.822243Z","steps":["trace[751282523] 'process raft request' (duration: 433.422338ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:06:34.822244Z","caller":"traceutil/trace.go:171","msg":"trace[28692802] linearizableReadLoop","detail":"{readStateIndex:8624; appliedIndex:8618; }","duration":"329.204305ms","start":"2026-04-20T05:06:34.493Z","end":"2026-04-20T05:06:34.822204Z","steps":["trace[28692802] 'read index received' (duration: 12.591937ms)","trace[28692802] 'applied index is now lower than readState.Index' (duration: 316.608478ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:06:34.822304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:34.388571Z","time spent":"433.704797ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:06:34.82216Z","caller":"traceutil/trace.go:171","msg":"trace[557644751] transaction","detail":"{read_only:false; response_revision:8333; number_of_response:1; }","duration":"433.529083ms","start":"2026-04-20T05:06:34.388567Z","end":"2026-04-20T05:06:34.822096Z","steps":["trace[557644751] 'process raft request' (duration: 433.324687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:34.822521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.501252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-20T05:06:34.822644Z","caller":"traceutil/trace.go:171","msg":"trace[1448293842] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8334; }","duration":"329.702748ms","start":"2026-04-20T05:06:34.49292Z","end":"2026-04-20T05:06:34.822623Z","steps":["trace[1448293842] 'agreement among raft nodes before linearized reading' (duration: 329.419401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:34.822689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:34.492898Z","time spent":"329.780719ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-20T05:06:34.822719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:34.388531Z","time spent":"433.950113ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:07:01.065562Z","caller":"traceutil/trace.go:171","msg":"trace[2042790144] linearizableReadLoop","detail":"{readStateIndex:8821; appliedIndex:8820; }","duration":"118.432004ms","start":"2026-04-20T05:07:00.947109Z","end":"2026-04-20T05:07:01.065541Z","steps":["trace[2042790144] 'read index received' (duration: 118.405953ms)","trace[2042790144] 'applied index is now lower than readState.Index' (duration: 25.271µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:01.065662Z","caller":"traceutil/trace.go:171","msg":"trace[1707332531] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"121.37535ms","start":"2026-04-20T05:07:00.944254Z","end":"2026-04-20T05:07:01.065629Z","steps":["trace[1707332531] 'process raft request' (duration: 121.176435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:01.065745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.272699ms","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-20T05:07:01.065745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.618458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T05:07:01.065786Z","caller":"traceutil/trace.go:171","msg":"trace[1303989273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8522; }","duration":"118.346181ms","start":"2026-04-20T05:07:00.94743Z","end":"2026-04-20T05:07:01.065776Z","steps":["trace[1303989273] 'agreement among raft nodes before linearized reading' (duration: 118.236529ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:01.065797Z","caller":"traceutil/trace.go:171","msg":"trace[46032103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8522; }","duration":"118.702001ms","start":"2026-04-20T05:07:00.947083Z","end":"2026-04-20T05:07:01.065785Z","steps":["trace[46032103] 'agreement among raft nodes before linearized reading' (duration: 118.563907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:01.06591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.353357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-04-20T05:07:01.065932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.413956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T05:07:01.065943Z","caller":"traceutil/trace.go:171","msg":"trace[243718885] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8522; }","duration":"117.406317ms","start":"2026-04-20T05:07:00.948527Z","end":"2026-04-20T05:07:01.065934Z","steps":["trace[243718885] 'agreement among raft nodes before linearized reading' (duration: 117.328495ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:01.065981Z","caller":"traceutil/trace.go:171","msg":"trace[1583932929] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8522; }","duration":"110.492827ms","start":"2026-04-20T05:07:00.955473Z","end":"2026-04-20T05:07:01.065966Z","steps":["trace[1583932929] 'agreement among raft nodes before linearized reading' (duration: 110.325683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:01.066102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.110468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T05:07:01.066208Z","caller":"traceutil/trace.go:171","msg":"trace[824019655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8522; }","duration":"110.254371ms","start":"2026-04-20T05:07:00.955935Z","end":"2026-04-20T05:07:01.06619Z","steps":["trace[824019655] 'agreement among raft nodes before linearized reading' (duration: 109.825499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:03.371332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592276850775248,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T05:07:03.408514Z","caller":"traceutil/trace.go:171","msg":"trace[1788536772] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"915.46623ms","start":"2026-04-20T05:07:02.493014Z","end":"2026-04-20T05:07:03.408481Z","steps":["trace[1788536772] 'process raft request' (duration: 915.290225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:03.408687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:02.493001Z","time spent":"915.592762ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:07:03.409606Z","caller":"traceutil/trace.go:171","msg":"trace[1650716689] linearizableReadLoop","detail":"{readStateIndex:8832; appliedIndex:8830; }","duration":"538.353912ms","start":"2026-04-20T05:07:02.871223Z","end":"2026-04-20T05:07:03.409577Z","steps":["trace[1650716689] 'read index received' (duration: 537.084139ms)","trace[1650716689] 'applied index is now lower than readState.Index' (duration: 1.268472ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:03.409692Z","caller":"traceutil/trace.go:171","msg":"trace[63418506] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"241.226ms","start":"2026-04-20T05:07:03.16845Z","end":"2026-04-20T05:07:03.409676Z","steps":["trace[63418506] 'process raft request' (duration: 241.001414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:03.409855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.597159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:07:03.40998Z","caller":"traceutil/trace.go:171","msg":"trace[928598843] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:8533; }","duration":"538.783814ms","start":"2026-04-20T05:07:02.871173Z","end":"2026-04-20T05:07:03.409957Z","steps":["trace[928598843] 'agreement among raft nodes before linearized reading' (duration: 538.592349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:03.410087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:02.871153Z","time spent":"538.913237ms","remote":"127.0.0.1:37082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-04-20T05:07:03.410135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.396611ms","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-20T05:07:03.410126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.889335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-20T05:07:03.410344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.527174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T05:07:03.410385Z","caller":"traceutil/trace.go:171","msg":"trace[1818178531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8533; }","duration":"331.606776ms","start":"2026-04-20T05:07:03.078767Z","end":"2026-04-20T05:07:03.410374Z","steps":["trace[1818178531] 'agreement among raft nodes before linearized reading' (duration: 331.497653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:03.410377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.787951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T05:07:03.410481Z","caller":"traceutil/trace.go:171","msg":"trace[1359932012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8533; }","duration":"321.922255ms","start":"2026-04-20T05:07:03.088536Z","end":"2026-04-20T05:07:03.410458Z","steps":["trace[1359932012] 'agreement among raft nodes before linearized reading' (duration: 321.645967ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:03.410293Z","caller":"traceutil/trace.go:171","msg":"trace[792628529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8533; }","duration":"322.073559ms","start":"2026-04-20T05:07:03.088208Z","end":"2026-04-20T05:07:03.410281Z","steps":["trace[792628529] 'agreement among raft nodes before linearized reading' (duration: 321.779332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:03.410609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:03.08852Z","time spent":"322.010348ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T05:07:03.410712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.142808ms","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-20T05:07:03.410683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:03.088201Z","time spent":"322.420678ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T05:07:03.410777Z","caller":"traceutil/trace.go:171","msg":"trace[1698684953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8533; }","duration":"340.282352ms","start":"2026-04-20T05:07:03.070478Z","end":"2026-04-20T05:07:03.410761Z","steps":["trace[1698684953] 'agreement among raft nodes before linearized reading' (duration: 339.43675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:03.410419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:03.078747Z","time spent":"331.663988ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T05:07:03.410213Z","caller":"traceutil/trace.go:171","msg":"trace[326374119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8533; }","duration":"331.481732ms","start":"2026-04-20T05:07:03.078703Z","end":"2026-04-20T05:07:03.410184Z","steps":["trace[326374119] 'agreement among raft nodes before linearized reading' (duration: 331.310728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:03.41091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:03.07046Z","time spent":"340.398215ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T05:07:03.410948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:03.078692Z","time spent":"332.245832ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T05:07:03.410206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.005727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-20T05:07:03.411029Z","caller":"traceutil/trace.go:171","msg":"trace[300417734] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8533; }","duration":"322.88269ms","start":"2026-04-20T05:07:03.088123Z","end":"2026-04-20T05:07:03.411006Z","steps":["trace[300417734] 'agreement among raft nodes before linearized reading' (duration: 321.946076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:03.411092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:03.088103Z","time spent":"322.974032ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T05:07:03.410182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.184811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8jnph\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T05:07:03.411178Z","caller":"traceutil/trace.go:171","msg":"trace[1869709260] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8jnph; range_end:; response_count:1; response_revision:8533; }","duration":"180.218718ms","start":"2026-04-20T05:07:03.230931Z","end":"2026-04-20T05:07:03.41115Z","steps":["trace[1869709260] 'agreement among raft nodes before linearized reading' (duration: 179.054648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:15.496663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.728479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-g2rgv\" ","response":"range_response_count:1 size:4665"} {"level":"info","ts":"2026-04-20T05:07:15.496752Z","caller":"traceutil/trace.go:171","msg":"trace[470120138] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-g2rgv; range_end:; response_count:1; response_revision:8632; }","duration":"358.839442ms","start":"2026-04-20T05:07:15.137893Z","end":"2026-04-20T05:07:15.496732Z","steps":["trace[470120138] 'range keys from in-memory index tree' (duration: 358.630967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:15.496797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:15.137854Z","time spent":"358.932434ms","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4688,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-g2rgv\" "} {"level":"warn","ts":"2026-04-20T05:07:15.497206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.926274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-g2rgv\" ","response":"range_response_count:1 size:4665"} {"level":"info","ts":"2026-04-20T05:07:15.497308Z","caller":"traceutil/trace.go:171","msg":"trace[1715110171] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-g2rgv; range_end:; response_count:1; response_revision:8632; }","duration":"359.476559ms","start":"2026-04-20T05:07:15.137806Z","end":"2026-04-20T05:07:15.497283Z","steps":["trace[1715110171] 'range keys from in-memory index tree' (duration: 358.803831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:15.497566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:15.13779Z","time spent":"359.756037ms","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4688,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-g2rgv\" "} {"level":"info","ts":"2026-04-20T05:07:16.272251Z","caller":"traceutil/trace.go:171","msg":"trace[2134969361] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"190.466543ms","start":"2026-04-20T05:07:16.081752Z","end":"2026-04-20T05:07:16.272219Z","steps":["trace[2134969361] 'process raft request' (duration: 190.292448ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:16.310627Z","caller":"traceutil/trace.go:171","msg":"trace[977943583] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"183.616135ms","start":"2026-04-20T05:07:16.126969Z","end":"2026-04-20T05:07:16.310585Z","steps":["trace[977943583] 'process raft request' (duration: 183.219535ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:16.610908Z","caller":"traceutil/trace.go:171","msg":"trace[646637653] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"288.118268ms","start":"2026-04-20T05:07:16.322754Z","end":"2026-04-20T05:07:16.610873Z","steps":["trace[646637653] 'process raft request' (duration: 287.866181ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:16.626324Z","caller":"traceutil/trace.go:171","msg":"trace[2103213557] linearizableReadLoop","detail":"{readStateIndex:8954; appliedIndex:8952; }","duration":"146.882462ms","start":"2026-04-20T05:07:16.479415Z","end":"2026-04-20T05:07:16.626298Z","steps":["trace[2103213557] 'read index received' (duration: 131.292878ms)","trace[2103213557] 'applied index is now lower than readState.Index' (duration: 15.588444ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:16.626458Z","caller":"traceutil/trace.go:171","msg":"trace[705828836] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"224.741842ms","start":"2026-04-20T05:07:16.401654Z","end":"2026-04-20T05:07:16.626396Z","steps":["trace[705828836] 'process raft request' (duration: 224.474916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:16.62652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.088948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:07:16.626582Z","caller":"traceutil/trace.go:171","msg":"trace[1975077946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8648; }","duration":"147.21055ms","start":"2026-04-20T05:07:16.479359Z","end":"2026-04-20T05:07:16.626569Z","steps":["trace[1975077946] 'agreement among raft nodes before linearized reading' (duration: 147.101757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:17.242594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.448526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:07:17.242686Z","caller":"traceutil/trace.go:171","msg":"trace[638508809] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:8652; }","duration":"140.62541ms","start":"2026-04-20T05:07:17.102039Z","end":"2026-04-20T05:07:17.242665Z","steps":["trace[638508809] 'count revisions from in-memory index tree' (duration: 140.354213ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:17.757382Z","caller":"traceutil/trace.go:171","msg":"trace[374339744] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"184.107108ms","start":"2026-04-20T05:07:17.573212Z","end":"2026-04-20T05:07:17.757319Z","steps":["trace[374339744] 'process raft request' (duration: 183.881413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:18.024825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.602343ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:07:18.025003Z","caller":"traceutil/trace.go:171","msg":"trace[1657778038] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"262.039191ms","start":"2026-04-20T05:07:17.762932Z","end":"2026-04-20T05:07:18.024971Z","steps":["trace[1657778038] 'process raft request' (duration: 85.16627ms)","trace[1657778038] 'compare' (duration: 176.349347ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:18.066998Z","caller":"traceutil/trace.go:171","msg":"trace[1098501282] transaction","detail":"{read_only:false; response_revision:8655; number_of_response:1; }","duration":"303.299653ms","start":"2026-04-20T05:07:17.763658Z","end":"2026-04-20T05:07:18.066958Z","steps":["trace[1098501282] 'process raft request' (duration: 303.006065ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:18.067328Z","caller":"traceutil/trace.go:171","msg":"trace[907872857] transaction","detail":"{read_only:false; response_revision:8656; number_of_response:1; }","duration":"302.969124ms","start":"2026-04-20T05:07:17.764304Z","end":"2026-04-20T05:07:18.067273Z","steps":["trace[907872857] 'process raft request' (duration: 302.592004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:18.067564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:17.76427Z","time spent":"303.163938ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:07:18.067588Z","caller":"traceutil/trace.go:171","msg":"trace[2043897268] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"301.859244ms","start":"2026-04-20T05:07:17.765696Z","end":"2026-04-20T05:07:18.067556Z","steps":["trace[2043897268] 'process raft request' (duration: 301.613638ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:18.067573Z","caller":"traceutil/trace.go:171","msg":"trace[1866997131] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"302.542802ms","start":"2026-04-20T05:07:17.764987Z","end":"2026-04-20T05:07:18.06753Z","steps":["trace[1866997131] 'process raft request' (duration: 302.148142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:18.067353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:17.763641Z","time spent":"303.59306ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:07:18.067736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:17.765678Z","time spent":"301.977778ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:07:18.067828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:17.764979Z","time spent":"302.730597ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:07:18.067967Z","caller":"traceutil/trace.go:171","msg":"trace[2116510678] linearizableReadLoop","detail":"{readStateIndex:8964; appliedIndex:8959; }","duration":"301.35267ms","start":"2026-04-20T05:07:17.766596Z","end":"2026-04-20T05:07:18.067948Z","steps":["trace[2116510678] 'read index received' (duration: 81.519375ms)","trace[2116510678] 'applied index is now lower than readState.Index' (duration: 219.831345ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:18.067941Z","caller":"traceutil/trace.go:171","msg":"trace[637141993] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"300.769506ms","start":"2026-04-20T05:07:17.767129Z","end":"2026-04-20T05:07:18.067899Z","steps":["trace[637141993] 'process raft request' (duration: 300.368865ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:18.068042Z","caller":"traceutil/trace.go:171","msg":"trace[1288161881] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"256.680041ms","start":"2026-04-20T05:07:17.811348Z","end":"2026-04-20T05:07:18.068028Z","steps":["trace[1288161881] 'process raft request' (duration: 256.413695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:18.068556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:17.767119Z","time spent":"300.954301ms","remote":"127.0.0.1:49632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:07:18.068735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.132871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228491"} {"level":"info","ts":"2026-04-20T05:07:18.068804Z","caller":"traceutil/trace.go:171","msg":"trace[204843417] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8660; }","duration":"302.225933ms","start":"2026-04-20T05:07:17.766564Z","end":"2026-04-20T05:07:18.068789Z","steps":["trace[204843417] 'agreement among raft nodes before linearized reading' (duration: 301.653508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:18.068842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:17.766536Z","time spent":"302.297596ms","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":228514,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-04-20T05:07:40.193382Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002416.wal"} {"level":"warn","ts":"2026-04-20T05:08:26.823306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.439903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:08:26.823387Z","caller":"traceutil/trace.go:171","msg":"trace[2121012387] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:9302; }","duration":"173.570535ms","start":"2026-04-20T05:08:26.649801Z","end":"2026-04-20T05:08:26.823371Z","steps":["trace[2121012387] 'count revisions from in-memory index tree' (duration: 173.363531ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:09:08.677434Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"adfb641115bf6cbd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T05:09:08.68185Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T05:09:08.681932Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T05:09:12.914324Z","caller":"traceutil/trace.go:171","msg":"trace[1730740151] linearizableReadLoop","detail":"{readStateIndex:10040; appliedIndex:10039; }","duration":"207.073038ms","start":"2026-04-20T05:09:12.707224Z","end":"2026-04-20T05:09:12.914297Z","steps":["trace[1730740151] 'read index received' (duration: 205.328393ms)","trace[1730740151] 'applied index is now lower than readState.Index' (duration: 1.743505ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:09:12.915048Z","caller":"traceutil/trace.go:171","msg":"trace[1233618184] transaction","detail":"{read_only:false; response_revision:9705; number_of_response:1; }","duration":"209.699575ms","start":"2026-04-20T05:09:12.705332Z","end":"2026-04-20T05:09:12.915032Z","steps":["trace[1233618184] 'process raft request' (duration: 207.284223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:09:12.915178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.94139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:09:12.915243Z","caller":"traceutil/trace.go:171","msg":"trace[1081558111] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:9705; }","duration":"208.071203ms","start":"2026-04-20T05:09:12.707156Z","end":"2026-04-20T05:09:12.915227Z","steps":["trace[1081558111] 'agreement among raft nodes before linearized reading' (duration: 207.94389ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:10:22.471559Z","caller":"traceutil/trace.go:171","msg":"trace[1483229213] transaction","detail":"{read_only:false; response_revision:10221; number_of_response:1; }","duration":"101.649734ms","start":"2026-04-20T05:10:22.369867Z","end":"2026-04-20T05:10:22.471516Z","steps":["trace[1483229213] 'process raft request' (duration: 50.90905ms)","trace[1483229213] 'compare' (duration: 50.453397ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:11:16.063362Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8106} {"level":"info","ts":"2026-04-20T05:11:16.129564Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8106,"took":"63.132877ms","hash":3010869331,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":29196288,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-20T05:11:16.129729Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3010869331,"revision":8106,"compact-revision":5975} {"level":"info","ts":"2026-04-20T05:16:16.069945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10652} {"level":"info","ts":"2026-04-20T05:16:16.14865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10652,"took":"76.660774ms","hash":440319624,"current-db-size-bytes":57053184,"current-db-size":"57 MB","current-db-size-in-use-bytes":21020672,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-20T05:16:16.148708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":440319624,"revision":10652,"compact-revision":8106}