{"level":"warn","ts":"2026-04-20T04:54:00.599646Z","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:54:00.599767Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.69: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.69:2380","--initial-cluster=instance=https://199.204.45.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.69: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:54:00.599854Z","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:54:00.599875Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-20T04:54:00.599903Z","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:54:00.600936Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"]} {"level":"info","ts":"2026-04-20T04:54:00.601045Z","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.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.69: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:54:00.605427Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.114437ms"} {"level":"info","ts":"2026-04-20T04:54:00.611671Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fe334179879fd0aa","cluster-id":"d79a8cccc23ced73"} {"level":"info","ts":"2026-04-20T04:54:00.611733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T04:54:00.611752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 0"} {"level":"info","ts":"2026-04-20T04:54:00.611763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fe334179879fd0aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T04:54:00.611776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 1"} {"level":"info","ts":"2026-04-20T04:54:00.611804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"warn","ts":"2026-04-20T04:54:00.623987Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T04:54:00.625602Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T04:54:00.627039Z","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:54:00.631882Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fe334179879fd0aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T04:54:00.632738Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fe334179879fd0aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T04:54:00.63273Z","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:54:00.632892Z","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:54:00.632906Z","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:54:00.634989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"info","ts":"2026-04-20T04:54:00.635187Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","added-peer-id":"fe334179879fd0aa","added-peer-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-20T04:54:00.63709Z","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:54:00.637202Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-20T04:54:00.637231Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-20T04:54:00.637455Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fe334179879fd0aa","initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T04:54:00.637489Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T04:54:01.412389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T04:54:01.412633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T04:54:01.412698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgPreVoteResp from fe334179879fd0aa at term 1"} {"level":"info","ts":"2026-04-20T04:54:01.412772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became candidate at term 2"} {"level":"info","ts":"2026-04-20T04:54:01.412818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgVoteResp from fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-20T04:54:01.412892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became leader at term 2"} {"level":"info","ts":"2026-04-20T04:54:01.412939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fe334179879fd0aa elected leader fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-20T04:54:01.41437Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:54:01.415295Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fe334179879fd0aa","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.69:2379]}","request-path":"/0/members/fe334179879fd0aa/attributes","cluster-id":"d79a8cccc23ced73","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T04:54:01.415307Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:54:01.415363Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:54:01.415683Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T04:54:01.415763Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T04:54:01.415944Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:54:01.416123Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:54:01.416203Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:54:01.418946Z","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:54:01.419323Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.69:2379"} {"level":"info","ts":"2026-04-20T04:54:11.795786Z","caller":"traceutil/trace.go:171","msg":"trace[359158437] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"146.053459ms","start":"2026-04-20T04:54:11.649704Z","end":"2026-04-20T04:54:11.795758Z","steps":["trace[359158437] 'process raft request' (duration: 145.905045ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:11.796197Z","caller":"traceutil/trace.go:171","msg":"trace[35976319] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:393; }","duration":"117.504657ms","start":"2026-04-20T04:54:11.678671Z","end":"2026-04-20T04:54:11.796176Z","steps":["trace[35976319] 'read index received' (duration: 81.496149ms)","trace[35976319] 'applied index is now lower than readState.Index' (duration: 36.007088ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:11.796263Z","caller":"traceutil/trace.go:171","msg":"trace[567059675] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"147.321029ms","start":"2026-04-20T04:54:11.648899Z","end":"2026-04-20T04:54:11.79622Z","steps":["trace[567059675] 'process raft request' (duration: 111.345192ms)","trace[567059675] 'compare' (duration: 35.215839ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:11.796233Z","caller":"traceutil/trace.go:171","msg":"trace[1752420005] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"140.743554ms","start":"2026-04-20T04:54:11.655469Z","end":"2026-04-20T04:54:11.796212Z","steps":["trace[1752420005] 'process raft request' (duration: 140.207581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:11.796486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.789353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-04-20T04:54:11.796585Z","caller":"traceutil/trace.go:171","msg":"trace[803647946] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:382; }","duration":"117.931227ms","start":"2026-04-20T04:54:11.67864Z","end":"2026-04-20T04:54:11.796571Z","steps":["trace[803647946] 'agreement among raft nodes before linearized reading' (duration: 117.657761ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:12.035228Z","caller":"traceutil/trace.go:171","msg":"trace[434902282] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"177.483159ms","start":"2026-04-20T04:54:11.857713Z","end":"2026-04-20T04:54:12.035196Z","steps":["trace[434902282] 'process raft request' (duration: 110.191325ms)","trace[434902282] 'compare' (duration: 67.150311ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:20.46871Z","caller":"traceutil/trace.go:171","msg":"trace[890469152] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"131.905425ms","start":"2026-04-20T04:54:20.336778Z","end":"2026-04-20T04:54:20.468684Z","steps":["trace[890469152] 'process raft request' (duration: 131.457615ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:31.64869Z","caller":"traceutil/trace.go:171","msg":"trace[1991874255] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"260.666426ms","start":"2026-04-20T04:54:31.388Z","end":"2026-04-20T04:54:31.648666Z","steps":["trace[1991874255] 'process raft request' (duration: 260.371919ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:31.659984Z","caller":"traceutil/trace.go:171","msg":"trace[2051005847] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"243.021541ms","start":"2026-04-20T04:54:31.416923Z","end":"2026-04-20T04:54:31.659944Z","steps":["trace[2051005847] 'process raft request' (duration: 237.639964ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:31.660695Z","caller":"traceutil/trace.go:171","msg":"trace[919921332] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"239.57494ms","start":"2026-04-20T04:54:31.420412Z","end":"2026-04-20T04:54:31.659987Z","steps":["trace[919921332] 'process raft request' (duration: 239.390345ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:37.910792Z","caller":"traceutil/trace.go:171","msg":"trace[1945885134] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"181.277104ms","start":"2026-04-20T04:54:37.729488Z","end":"2026-04-20T04:54:37.910765Z","steps":["trace[1945885134] 'process raft request' (duration: 180.71293ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:38.142762Z","caller":"traceutil/trace.go:171","msg":"trace[1197485475] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"175.458868ms","start":"2026-04-20T04:54:37.967269Z","end":"2026-04-20T04:54:38.142728Z","steps":["trace[1197485475] 'process raft request' (duration: 175.291424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:38.754658Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003656128309275,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T04:54:38.855169Z","caller":"traceutil/trace.go:171","msg":"trace[715334571] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"636.772821ms","start":"2026-04-20T04:54:38.218368Z","end":"2026-04-20T04:54:38.855141Z","steps":["trace[715334571] 'process raft request' (duration: 610.137074ms)","trace[715334571] 'compare' (duration: 26.183896ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:38.855427Z","caller":"traceutil/trace.go:171","msg":"trace[1796429963] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:654; }","duration":"601.609974ms","start":"2026-04-20T04:54:38.253797Z","end":"2026-04-20T04:54:38.855407Z","steps":["trace[1796429963] 'read index received' (duration: 574.65449ms)","trace[1796429963] 'applied index is now lower than readState.Index' (duration: 26.954014ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:38.855519Z","caller":"traceutil/trace.go:171","msg":"trace[451213216] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"635.129272ms","start":"2026-04-20T04:54:38.220362Z","end":"2026-04-20T04:54:38.855491Z","steps":["trace[451213216] 'process raft request' (duration: 634.913687ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:38.855505Z","caller":"traceutil/trace.go:171","msg":"trace[2133057429] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"636.70082ms","start":"2026-04-20T04:54:38.218769Z","end":"2026-04-20T04:54:38.85547Z","steps":["trace[2133057429] 'process raft request' (duration: 636.321051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:38.855749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.04986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-20T04:54:38.855737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:38.218732Z","time spent":"636.947556ms","remote":"127.0.0.1:54964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:54:38.855816Z","caller":"traceutil/trace.go:171","msg":"trace[1230060633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"306.159432ms","start":"2026-04-20T04:54:38.54964Z","end":"2026-04-20T04:54:38.8558Z","steps":["trace[1230060633] 'agreement among raft nodes before linearized reading' (duration: 305.957898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:38.855835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:38.218359Z","time spent":"636.904875ms","remote":"127.0.0.1:53940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:54:38.855861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:38.549618Z","time spent":"306.231545ms","remote":"127.0.0.1:53960","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:54:38.855873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.025774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gk2xk\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-20T04:54:38.855959Z","caller":"traceutil/trace.go:171","msg":"trace[539764928] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gk2xk; range_end:; response_count:1; response_revision:630; }","duration":"602.192608ms","start":"2026-04-20T04:54:38.253752Z","end":"2026-04-20T04:54:38.855944Z","steps":["trace[539764928] 'agreement among raft nodes before linearized reading' (duration: 601.790378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:38.856036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:38.253726Z","time spent":"602.299311ms","remote":"127.0.0.1:55062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-gk2xk\" "} {"level":"warn","ts":"2026-04-20T04:54:38.855882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:38.220334Z","time spent":"635.272626ms","remote":"127.0.0.1:55062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:54:40.537526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.182653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:54:40.537757Z","caller":"traceutil/trace.go:171","msg":"trace[523188468] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"156.243485ms","start":"2026-04-20T04:54:40.381496Z","end":"2026-04-20T04:54:40.537739Z","steps":["trace[523188468] 'process raft request' (duration: 156.204174ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:40.537836Z","caller":"traceutil/trace.go:171","msg":"trace[1598357962] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"156.85279ms","start":"2026-04-20T04:54:40.380955Z","end":"2026-04-20T04:54:40.537808Z","steps":["trace[1598357962] 'process raft request' (duration: 156.694336ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:40.537796Z","caller":"traceutil/trace.go:171","msg":"trace[1475097397] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"157.204378ms","start":"2026-04-20T04:54:40.380532Z","end":"2026-04-20T04:54:40.537736Z","steps":["trace[1475097397] 'process raft request' (duration: 26.553204ms)","trace[1475097397] 'compare' (duration: 130.025649ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:40.614278Z","caller":"traceutil/trace.go:171","msg":"trace[238307539] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"227.458991ms","start":"2026-04-20T04:54:40.386804Z","end":"2026-04-20T04:54:40.614263Z","steps":["trace[238307539] 'process raft request' (duration: 227.3905ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:40.614365Z","caller":"traceutil/trace.go:171","msg":"trace[305197475] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"228.325011ms","start":"2026-04-20T04:54:40.386008Z","end":"2026-04-20T04:54:40.614333Z","steps":["trace[305197475] 'process raft request' (duration: 228.038444ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:43.94411Z","caller":"traceutil/trace.go:171","msg":"trace[463794352] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"119.02601ms","start":"2026-04-20T04:54:43.825017Z","end":"2026-04-20T04:54:43.944043Z","steps":["trace[463794352] 'process raft request' (duration: 118.678972ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:51.478155Z","caller":"traceutil/trace.go:171","msg":"trace[2049904266] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"118.888927ms","start":"2026-04-20T04:54:51.359212Z","end":"2026-04-20T04:54:51.478101Z","steps":["trace[2049904266] 'process raft request' (duration: 48.477871ms)","trace[2049904266] 'compare' (duration: 70.241182ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:51.478501Z","caller":"traceutil/trace.go:171","msg":"trace[1989780593] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"115.083557ms","start":"2026-04-20T04:54:51.363388Z","end":"2026-04-20T04:54:51.478471Z","steps":["trace[1989780593] 'process raft request' (duration: 114.989545ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:51.478665Z","caller":"traceutil/trace.go:171","msg":"trace[915444632] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"119.161423ms","start":"2026-04-20T04:54:51.359478Z","end":"2026-04-20T04:54:51.47864Z","steps":["trace[915444632] 'process raft request' (duration: 118.772284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:54.209994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.93801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:54:54.210202Z","caller":"traceutil/trace.go:171","msg":"trace[31214740] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"421.919736ms","start":"2026-04-20T04:54:53.78823Z","end":"2026-04-20T04:54:54.21015Z","steps":["trace[31214740] 'process raft request' (duration: 148.663148ms)","trace[31214740] 'compare' (duration: 272.733526ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:54:54.210339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:53.788202Z","time spent":"422.067129ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:55:20.73211Z","caller":"traceutil/trace.go:171","msg":"trace[152042231] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"110.432308ms","start":"2026-04-20T04:55:20.621638Z","end":"2026-04-20T04:55:20.73207Z","steps":["trace[152042231] 'process raft request' (duration: 109.839094ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:45.594669Z","caller":"traceutil/trace.go:171","msg":"trace[1287426202] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"104.171691ms","start":"2026-04-20T04:55:45.490471Z","end":"2026-04-20T04:55:45.594643Z","steps":["trace[1287426202] 'process raft request' (duration: 85.546803ms)","trace[1287426202] 'compare' (duration: 18.506376ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:56:01.846488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.608578ms","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.846641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.134144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-20T04:56:01.846635Z","caller":"traceutil/trace.go:171","msg":"trace[2113062648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1812; }","duration":"171.809243ms","start":"2026-04-20T04:56:01.674802Z","end":"2026-04-20T04:56:01.846611Z","steps":["trace[2113062648] 'range keys from in-memory index tree' (duration: 171.564487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:01.846562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.364847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9032"} {"level":"info","ts":"2026-04-20T04:56:01.846704Z","caller":"traceutil/trace.go:171","msg":"trace[1782886763] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1812; }","duration":"210.210876ms","start":"2026-04-20T04:56:01.636456Z","end":"2026-04-20T04:56:01.846667Z","steps":["trace[1782886763] 'range keys from in-memory index tree' (duration: 209.812247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:01.846696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.438985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-20T04:56:01.846788Z","caller":"traceutil/trace.go:171","msg":"trace[2100335869] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1812; }","duration":"225.65269ms","start":"2026-04-20T04:56:01.62112Z","end":"2026-04-20T04:56:01.846772Z","steps":["trace[2100335869] 'range keys from in-memory index tree' (duration: 225.266041ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:01.846751Z","caller":"traceutil/trace.go:171","msg":"trace[716518488] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1812; }","duration":"178.569021ms","start":"2026-04-20T04:56:01.668139Z","end":"2026-04-20T04:56:01.846708Z","steps":["trace[716518488] 'range keys from in-memory index tree' (duration: 178.120001ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:01.988447Z","caller":"traceutil/trace.go:171","msg":"trace[895138725] linearizableReadLoop","detail":"{readStateIndex:1870; appliedIndex:1869; }","duration":"140.6993ms","start":"2026-04-20T04:56:01.847719Z","end":"2026-04-20T04:56:01.988418Z","steps":["trace[895138725] 'read index received' (duration: 140.584668ms)","trace[895138725] 'applied index is now lower than readState.Index' (duration: 113.322µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:56:01.988533Z","caller":"traceutil/trace.go:171","msg":"trace[285627706] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"258.809979ms","start":"2026-04-20T04:56:01.729689Z","end":"2026-04-20T04:56:01.988499Z","steps":["trace[285627706] 'process raft request' (duration: 258.537253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:01.988647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.901985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:56:01.988692Z","caller":"traceutil/trace.go:171","msg":"trace[776350807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1813; }","duration":"141.006798ms","start":"2026-04-20T04:56:01.847674Z","end":"2026-04-20T04:56:01.988681Z","steps":["trace[776350807] 'agreement among raft nodes before linearized reading' (duration: 140.890215ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:02.07881Z","caller":"traceutil/trace.go:171","msg":"trace[209674470] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"222.481924ms","start":"2026-04-20T04:56:01.856287Z","end":"2026-04-20T04:56:02.078769Z","steps":["trace[209674470] 'process raft request' (duration: 222.31507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:02.078825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.184389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-04-20T04:56:02.078966Z","caller":"traceutil/trace.go:171","msg":"trace[488568691] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"227.632936ms","start":"2026-04-20T04:56:01.851285Z","end":"2026-04-20T04:56:02.078918Z","steps":["trace[488568691] 'process raft request' (duration: 220.177621ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:02.079011Z","caller":"traceutil/trace.go:171","msg":"trace[1239136981] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1815; }","duration":"228.397484ms","start":"2026-04-20T04:56:01.850581Z","end":"2026-04-20T04:56:02.078979Z","steps":["trace[1239136981] 'agreement among raft nodes before linearized reading' (duration: 228.090597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:02.079047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.787717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-20T04:56:02.079134Z","caller":"traceutil/trace.go:171","msg":"trace[471691484] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1815; }","duration":"127.93513ms","start":"2026-04-20T04:56:01.951171Z","end":"2026-04-20T04:56:02.079106Z","steps":["trace[471691484] 'agreement among raft nodes before linearized reading' (duration: 127.665104ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:02.304986Z","caller":"traceutil/trace.go:171","msg":"trace[143154900] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"220.16143ms","start":"2026-04-20T04:56:02.084794Z","end":"2026-04-20T04:56:02.304956Z","steps":["trace[143154900] 'process raft request' (duration: 131.156056ms)","trace[143154900] 'compare' (duration: 88.82265ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:56:02.305434Z","caller":"traceutil/trace.go:171","msg":"trace[82856089] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"220.059898ms","start":"2026-04-20T04:56:02.085337Z","end":"2026-04-20T04:56:02.305397Z","steps":["trace[82856089] 'process raft request' (duration: 219.559306ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:02.305987Z","caller":"traceutil/trace.go:171","msg":"trace[62526756] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"220.443297ms","start":"2026-04-20T04:56:02.085496Z","end":"2026-04-20T04:56:02.30594Z","steps":["trace[62526756] 'process raft request' (duration: 219.75228ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:02.306079Z","caller":"traceutil/trace.go:171","msg":"trace[1193455932] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1873; }","duration":"178.48808ms","start":"2026-04-20T04:56:02.127561Z","end":"2026-04-20T04:56:02.306049Z","steps":["trace[1193455932] 'read index received' (duration: 88.399041ms)","trace[1193455932] 'applied index is now lower than readState.Index' (duration: 90.087679ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:56:02.306143Z","caller":"traceutil/trace.go:171","msg":"trace[1355038448] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"219.945825ms","start":"2026-04-20T04:56:02.086169Z","end":"2026-04-20T04:56:02.306115Z","steps":["trace[1355038448] 'process raft request' (duration: 219.676218ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:02.306315Z","caller":"traceutil/trace.go:171","msg":"trace[660061855] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"218.976553ms","start":"2026-04-20T04:56:02.087302Z","end":"2026-04-20T04:56:02.306279Z","steps":["trace[660061855] 'process raft request' (duration: 218.667195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:02.306413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.806777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-04-20T04:56:02.306512Z","caller":"traceutil/trace.go:171","msg":"trace[1656335975] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1821; }","duration":"178.993132ms","start":"2026-04-20T04:56:02.1275Z","end":"2026-04-20T04:56:02.306494Z","steps":["trace[1656335975] 'agreement among raft nodes before linearized reading' (duration: 178.656114ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:45.379151Z","caller":"traceutil/trace.go:171","msg":"trace[1611393575] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"169.588626ms","start":"2026-04-20T04:56:45.209539Z","end":"2026-04-20T04:56:45.379128Z","steps":["trace[1611393575] 'process raft request' (duration: 169.458693ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:45.835091Z","caller":"traceutil/trace.go:171","msg":"trace[1802533552] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2236; }","duration":"129.30794ms","start":"2026-04-20T04:56:45.705756Z","end":"2026-04-20T04:56:45.835064Z","steps":["trace[1802533552] 'read index received' (duration: 32.924375ms)","trace[1802533552] 'applied index is now lower than readState.Index' (duration: 96.382405ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:56:45.835229Z","caller":"traceutil/trace.go:171","msg":"trace[1601963701] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"146.462673ms","start":"2026-04-20T04:56:45.688753Z","end":"2026-04-20T04:56:45.835215Z","steps":["trace[1601963701] 'process raft request' (duration: 49.988395ms)","trace[1601963701] 'compare' (duration: 96.209612ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:56:45.835454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.610564ms","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:56:45.835539Z","caller":"traceutil/trace.go:171","msg":"trace[1301432268] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2162; }","duration":"128.726286ms","start":"2026-04-20T04:56:45.706793Z","end":"2026-04-20T04:56:45.83552Z","steps":["trace[1301432268] 'agreement among raft nodes before linearized reading' (duration: 128.532782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:56:45.83555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.653004ms","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:56:45.83554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.475748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-04-20T04:56:45.835605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.838683ms","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:56:45.835628Z","caller":"traceutil/trace.go:171","msg":"trace[437776138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2162; }","duration":"128.763477ms","start":"2026-04-20T04:56:45.706848Z","end":"2026-04-20T04:56:45.835611Z","steps":["trace[437776138] 'agreement among raft nodes before linearized reading' (duration: 128.569133ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:45.83565Z","caller":"traceutil/trace.go:171","msg":"trace[1902900237] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2162; }","duration":"113.620772ms","start":"2026-04-20T04:56:45.722003Z","end":"2026-04-20T04:56:45.835623Z","steps":["trace[1902900237] 'agreement among raft nodes before linearized reading' (duration: 113.363585ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:56:45.835679Z","caller":"traceutil/trace.go:171","msg":"trace[765130021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2162; }","duration":"129.958775ms","start":"2026-04-20T04:56:45.705703Z","end":"2026-04-20T04:56:45.835662Z","steps":["trace[765130021] 'agreement among raft nodes before linearized reading' (duration: 129.767591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:57:28.715158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.563381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-20T04:57:28.715249Z","caller":"traceutil/trace.go:171","msg":"trace[463899087] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2505; }","duration":"233.694644ms","start":"2026-04-20T04:57:28.481539Z","end":"2026-04-20T04:57:28.715233Z","steps":["trace[463899087] 'range keys from in-memory index tree' (duration: 233.396557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:57:28.715396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.912826ms","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:57:28.715452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.34431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-04-20T04:57:28.71549Z","caller":"traceutil/trace.go:171","msg":"trace[453770552] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2505; }","duration":"236.065379ms","start":"2026-04-20T04:57:28.479403Z","end":"2026-04-20T04:57:28.715468Z","steps":["trace[453770552] 'range keys from in-memory index tree' (duration: 235.729062ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:57:28.71551Z","caller":"traceutil/trace.go:171","msg":"trace[1397505741] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2505; }","duration":"103.418591ms","start":"2026-04-20T04:57:28.612072Z","end":"2026-04-20T04:57:28.71549Z","steps":["trace[1397505741] 'range keys from in-memory index tree' (duration: 103.207276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:58:03.740781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.552977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:58:03.740932Z","caller":"traceutil/trace.go:171","msg":"trace[236582348] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"172.082116ms","start":"2026-04-20T04:58:03.568821Z","end":"2026-04-20T04:58:03.740903Z","steps":["trace[236582348] 'process raft request' (duration: 20.284523ms)","trace[236582348] 'compare' (duration: 151.401193ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:58:03.812823Z","caller":"traceutil/trace.go:171","msg":"trace[536560509] linearizableReadLoop","detail":"{readStateIndex:2855; appliedIndex:2854; }","duration":"199.637999ms","start":"2026-04-20T04:58:03.61315Z","end":"2026-04-20T04:58:03.812788Z","steps":["trace[536560509] 'read index received' (duration: 199.409164ms)","trace[536560509] 'applied index is now lower than readState.Index' (duration: 227.395µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:58:03.812981Z","caller":"traceutil/trace.go:171","msg":"trace[887441385] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"241.609958ms","start":"2026-04-20T04:58:03.571318Z","end":"2026-04-20T04:58:03.812928Z","steps":["trace[887441385] 'process raft request' (duration: 241.27302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:58:03.81307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.897004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:58:03.813116Z","caller":"traceutil/trace.go:171","msg":"trace[609329294] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2747; }","duration":"200.027017ms","start":"2026-04-20T04:58:03.613076Z","end":"2026-04-20T04:58:03.813103Z","steps":["trace[609329294] 'agreement among raft nodes before linearized reading' (duration: 199.905945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:58:03.813174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.050751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:58:03.813292Z","caller":"traceutil/trace.go:171","msg":"trace[245652251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2747; }","duration":"138.239065ms","start":"2026-04-20T04:58:03.675026Z","end":"2026-04-20T04:58:03.813265Z","steps":["trace[245652251] 'agreement among raft nodes before linearized reading' (duration: 138.03544ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:58:03.935848Z","caller":"traceutil/trace.go:171","msg":"trace[877385394] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"117.095573ms","start":"2026-04-20T04:58:03.818705Z","end":"2026-04-20T04:58:03.935801Z","steps":["trace[877385394] 'process raft request' (duration: 115.370832ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:00.570309Z","caller":"traceutil/trace.go:171","msg":"trace[81939195] transaction","detail":"{read_only:false; response_revision:3300; number_of_response:1; }","duration":"198.688375ms","start":"2026-04-20T04:59:00.371582Z","end":"2026-04-20T04:59:00.57027Z","steps":["trace[81939195] 'process raft request' (duration: 198.329317ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:00.694408Z","caller":"traceutil/trace.go:171","msg":"trace[403473462] transaction","detail":"{read_only:false; response_revision:3302; number_of_response:1; }","duration":"119.195526ms","start":"2026-04-20T04:59:00.57519Z","end":"2026-04-20T04:59:00.694386Z","steps":["trace[403473462] 'process raft request' (duration: 118.92785ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:00.694399Z","caller":"traceutil/trace.go:171","msg":"trace[659700] transaction","detail":"{read_only:false; response_revision:3303; number_of_response:1; }","duration":"119.144846ms","start":"2026-04-20T04:59:00.575224Z","end":"2026-04-20T04:59:00.694369Z","steps":["trace[659700] 'process raft request' (duration: 119.056974ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:00.694703Z","caller":"traceutil/trace.go:171","msg":"trace[325186999] transaction","detail":"{read_only:false; response_revision:3304; number_of_response:1; }","duration":"119.437263ms","start":"2026-04-20T04:59:00.575224Z","end":"2026-04-20T04:59:00.694662Z","steps":["trace[325186999] 'process raft request' (duration: 119.106955ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:00.694818Z","caller":"traceutil/trace.go:171","msg":"trace[111393917] transaction","detail":"{read_only:false; response_revision:3305; number_of_response:1; }","duration":"117.926677ms","start":"2026-04-20T04:59:00.576875Z","end":"2026-04-20T04:59:00.694802Z","steps":["trace[111393917] 'process raft request' (duration: 117.674981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:14.723079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.7709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:00:14.723197Z","caller":"traceutil/trace.go:171","msg":"trace[1434755254] linearizableReadLoop","detail":"{readStateIndex:4514; appliedIndex:4513; }","duration":"101.62599ms","start":"2026-04-20T05:00:14.621555Z","end":"2026-04-20T05:00:14.723181Z","steps":["trace[1434755254] 'read index received' (duration: 31.06µs)","trace[1434755254] 'applied index is now lower than readState.Index' (duration: 101.59327ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:14.723453Z","caller":"traceutil/trace.go:171","msg":"trace[185964697] transaction","detail":"{read_only:false; response_revision:4330; number_of_response:1; }","duration":"115.011264ms","start":"2026-04-20T05:00:14.608434Z","end":"2026-04-20T05:00:14.723445Z","steps":["trace[185964697] 'compare' (duration: 106.651618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:14.723673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.114381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3755a71a-c82f-4463-9ec8-a4d78a8eae51\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-04-20T05:00:14.7237Z","caller":"traceutil/trace.go:171","msg":"trace[543793601] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3755a71a-c82f-4463-9ec8-a4d78a8eae51; range_end:; response_count:1; response_revision:4330; }","duration":"102.175713ms","start":"2026-04-20T05:00:14.621517Z","end":"2026-04-20T05:00:14.723693Z","steps":["trace[543793601] 'agreement among raft nodes before linearized reading' (duration: 102.06973ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:16.16867Z","caller":"traceutil/trace.go:171","msg":"trace[564929919] transaction","detail":"{read_only:false; response_revision:4350; number_of_response:1; }","duration":"115.501635ms","start":"2026-04-20T05:00:16.053147Z","end":"2026-04-20T05:00:16.168648Z","steps":["trace[564929919] 'process raft request' (duration: 115.345141ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:16.169458Z","caller":"traceutil/trace.go:171","msg":"trace[2083430988] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4350; }","duration":"113.514239ms","start":"2026-04-20T05:00:16.055932Z","end":"2026-04-20T05:00:16.169447Z","steps":["trace[2083430988] 'process raft request' (duration: 113.359995ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:16.16946Z","caller":"traceutil/trace.go:171","msg":"trace[1168531697] linearizableReadLoop","detail":"{readStateIndex:4535; appliedIndex:4533; }","duration":"109.948335ms","start":"2026-04-20T05:00:16.059484Z","end":"2026-04-20T05:00:16.169433Z","steps":["trace[1168531697] 'read index received' (duration: 109.085115ms)","trace[1168531697] 'applied index is now lower than readState.Index' (duration: 861.64µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:16.169755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.271012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9168f544-efc1-4b5d-a181-2f25ed505b0c\" ","response":"range_response_count:1 size:4052"} {"level":"info","ts":"2026-04-20T05:00:16.1698Z","caller":"traceutil/trace.go:171","msg":"trace[111267976] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9168f544-efc1-4b5d-a181-2f25ed505b0c; range_end:; response_count:1; response_revision:4350; }","duration":"110.385136ms","start":"2026-04-20T05:00:16.059403Z","end":"2026-04-20T05:00:16.169788Z","steps":["trace[111267976] 'agreement among raft nodes before linearized reading' (duration: 110.171891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:19.89754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.883474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:00:19.897771Z","caller":"traceutil/trace.go:171","msg":"trace[1247737506] transaction","detail":"{read_only:false; response_revision:4506; number_of_response:1; }","duration":"388.53603ms","start":"2026-04-20T05:00:19.5092Z","end":"2026-04-20T05:00:19.897736Z","steps":["trace[1247737506] 'process raft request' (duration: 190.370669ms)","trace[1247737506] 'compare' (duration: 197.542276ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:19.897839Z","caller":"traceutil/trace.go:171","msg":"trace[1396523130] linearizableReadLoop","detail":"{readStateIndex:4697; appliedIndex:4696; }","duration":"362.98521ms","start":"2026-04-20T05:00:19.534822Z","end":"2026-04-20T05:00:19.897807Z","steps":["trace[1396523130] 'read index received' (duration: 164.901161ms)","trace[1396523130] 'applied index is now lower than readState.Index' (duration: 198.081029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:19.897934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:19.509168Z","time spent":"388.725843ms","remote":"127.0.0.1:59210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T05:00:19.94371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.867046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6374611c-2033-4c9a-97a4-7d5dcdf91428\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-20T05:00:19.943791Z","caller":"traceutil/trace.go:171","msg":"trace[1563509939] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6374611c-2033-4c9a-97a4-7d5dcdf91428; range_end:; response_count:1; response_revision:4506; }","duration":"408.959058ms","start":"2026-04-20T05:00:19.534811Z","end":"2026-04-20T05:00:19.943771Z","steps":["trace[1563509939] 'agreement among raft nodes before linearized reading' (duration: 363.084264ms)","trace[1563509939] 'range keys from in-memory index tree' (duration: 45.69972ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:19.94382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:19.534709Z","time spent":"409.105581ms","remote":"127.0.0.1:55062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6374611c-2033-4c9a-97a4-7d5dcdf91428\" "} {"level":"warn","ts":"2026-04-20T05:00:19.944038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.924392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-20T05:00:19.944065Z","caller":"traceutil/trace.go:171","msg":"trace[1999929360] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4506; }","duration":"276.001744ms","start":"2026-04-20T05:00:19.668057Z","end":"2026-04-20T05:00:19.944059Z","steps":["trace[1999929360] 'agreement among raft nodes before linearized reading' (duration: 275.925642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:19.94421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.186903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-20T05:00:19.944235Z","caller":"traceutil/trace.go:171","msg":"trace[385284366] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4506; }","duration":"409.239765ms","start":"2026-04-20T05:00:19.534988Z","end":"2026-04-20T05:00:19.944228Z","steps":["trace[385284366] 'agreement among raft nodes before linearized reading' (duration: 409.178273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:19.944251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:19.534972Z","time spent":"409.274535ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-20T05:00:33.88526Z","caller":"traceutil/trace.go:171","msg":"trace[1493371846] linearizableReadLoop","detail":"{readStateIndex:4966; appliedIndex:4965; }","duration":"104.59226ms","start":"2026-04-20T05:00:33.780639Z","end":"2026-04-20T05:00:33.885231Z","steps":["trace[1493371846] 'read index received' (duration: 104.306943ms)","trace[1493371846] 'applied index is now lower than readState.Index' (duration: 283.687µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:33.885315Z","caller":"traceutil/trace.go:171","msg":"trace[1329263053] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"107.222802ms","start":"2026-04-20T05:00:33.778052Z","end":"2026-04-20T05:00:33.885275Z","steps":["trace[1329263053] 'process raft request' (duration: 106.883114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:33.885454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.811145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:00:33.885499Z","caller":"traceutil/trace.go:171","msg":"trace[444732084] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:4765; }","duration":"104.906037ms","start":"2026-04-20T05:00:33.78058Z","end":"2026-04-20T05:00:33.885486Z","steps":["trace[444732084] 'agreement among raft nodes before linearized reading' (duration: 104.760843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:34.322849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.951821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-20T05:00:34.322943Z","caller":"traceutil/trace.go:171","msg":"trace[1735415705] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4768; }","duration":"305.100795ms","start":"2026-04-20T05:00:34.017819Z","end":"2026-04-20T05:00:34.322919Z","steps":["trace[1735415705] 'range keys from in-memory index tree' (duration: 304.730286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:34.323175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.711626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"info","ts":"2026-04-20T05:00:34.323266Z","caller":"traceutil/trace.go:171","msg":"trace[1939249918] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4768; }","duration":"152.851029ms","start":"2026-04-20T05:00:34.170395Z","end":"2026-04-20T05:00:34.323246Z","steps":["trace[1939249918] 'range keys from in-memory index tree' (duration: 152.340988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:34.322999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:00:34.017795Z","time spent":"305.190867ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-20T05:00:34.324005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.841234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-5v8t7\" ","response":"range_response_count:1 size:7812"} {"level":"info","ts":"2026-04-20T05:00:34.324086Z","caller":"traceutil/trace.go:171","msg":"trace[1291087055] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-c47d949f5-5v8t7; range_end:; response_count:1; response_revision:4768; }","duration":"244.970317ms","start":"2026-04-20T05:00:34.079096Z","end":"2026-04-20T05:00:34.324066Z","steps":["trace[1291087055] 'range keys from in-memory index tree' (duration: 243.486382ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:34.329045Z","caller":"traceutil/trace.go:171","msg":"trace[540844709] linearizableReadLoop","detail":"{readStateIndex:4970; appliedIndex:4969; }","duration":"140.828218ms","start":"2026-04-20T05:00:34.188191Z","end":"2026-04-20T05:00:34.329019Z","steps":["trace[540844709] 'read index received' (duration: 140.613963ms)","trace[540844709] 'applied index is now lower than readState.Index' (duration: 213.245µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:34.329191Z","caller":"traceutil/trace.go:171","msg":"trace[1753855115] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"157.968049ms","start":"2026-04-20T05:00:34.171205Z","end":"2026-04-20T05:00:34.329173Z","steps":["trace[1753855115] 'process raft request' (duration: 157.674512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:34.329246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.041303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-20T05:00:34.329283Z","caller":"traceutil/trace.go:171","msg":"trace[1984935944] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4769; }","duration":"141.109675ms","start":"2026-04-20T05:00:34.188164Z","end":"2026-04-20T05:00:34.329274Z","steps":["trace[1984935944] 'agreement among raft nodes before linearized reading' (duration: 140.939641ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:43.454634Z","caller":"traceutil/trace.go:171","msg":"trace[335467585] linearizableReadLoop","detail":"{readStateIndex:5132; appliedIndex:5131; }","duration":"196.135293ms","start":"2026-04-20T05:00:43.258478Z","end":"2026-04-20T05:00:43.454613Z","steps":["trace[335467585] 'read index received' (duration: 195.845706ms)","trace[335467585] 'applied index is now lower than readState.Index' (duration: 288.667µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:43.454711Z","caller":"traceutil/trace.go:171","msg":"trace[2078247137] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"231.487001ms","start":"2026-04-20T05:00:43.223191Z","end":"2026-04-20T05:00:43.454678Z","steps":["trace[2078247137] 'process raft request' (duration: 231.212145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:43.454969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.227915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T05:00:43.454969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.510122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T05:00:43.455017Z","caller":"traceutil/trace.go:171","msg":"trace[2065890107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4927; }","duration":"196.316667ms","start":"2026-04-20T05:00:43.258688Z","end":"2026-04-20T05:00:43.455005Z","steps":["trace[2065890107] 'agreement among raft nodes before linearized reading' (duration: 196.071601ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:43.455032Z","caller":"traceutil/trace.go:171","msg":"trace[858625844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4927; }","duration":"196.595504ms","start":"2026-04-20T05:00:43.258421Z","end":"2026-04-20T05:00:43.455016Z","steps":["trace[858625844] 'agreement among raft nodes before linearized reading' (duration: 196.343708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:43.455122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.395366ms","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:00:43.45517Z","caller":"traceutil/trace.go:171","msg":"trace[911816593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4927; }","duration":"195.476398ms","start":"2026-04-20T05:00:43.259682Z","end":"2026-04-20T05:00:43.455159Z","steps":["trace[911816593] 'agreement among raft nodes before linearized reading' (duration: 195.320254ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:56.451522Z","caller":"traceutil/trace.go:171","msg":"trace[59550707] transaction","detail":"{read_only:false; response_revision:5111; number_of_response:1; }","duration":"113.474277ms","start":"2026-04-20T05:00:56.338016Z","end":"2026-04-20T05:00:56.45149Z","steps":["trace[59550707] 'process raft request' (duration: 104.903946ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:56.495574Z","caller":"traceutil/trace.go:171","msg":"trace[1513331971] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"156.956076ms","start":"2026-04-20T05:00:56.338602Z","end":"2026-04-20T05:00:56.495558Z","steps":["trace[1513331971] 'process raft request' (duration: 156.808312ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:56.495966Z","caller":"traceutil/trace.go:171","msg":"trace[142636753] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"157.012217ms","start":"2026-04-20T05:00:56.338909Z","end":"2026-04-20T05:00:56.495921Z","steps":["trace[142636753] 'process raft request' (duration: 156.599178ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:56.496061Z","caller":"traceutil/trace.go:171","msg":"trace[2011753412] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"157.055118ms","start":"2026-04-20T05:00:56.338974Z","end":"2026-04-20T05:00:56.496029Z","steps":["trace[2011753412] 'process raft request' (duration: 156.847583ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:56.619275Z","caller":"traceutil/trace.go:171","msg":"trace[714418023] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"113.653942ms","start":"2026-04-20T05:00:56.50559Z","end":"2026-04-20T05:00:56.619243Z","steps":["trace[714418023] 'process raft request' (duration: 99.666444ms)","trace[714418023] 'compare' (duration: 13.851644ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:57.178375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.390691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:00:57.178568Z","caller":"traceutil/trace.go:171","msg":"trace[1297608698] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5118; }","duration":"106.645597ms","start":"2026-04-20T05:00:57.071898Z","end":"2026-04-20T05:00:57.178544Z","steps":["trace[1297608698] 'count revisions from in-memory index tree' (duration: 106.300319ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:57.690715Z","caller":"traceutil/trace.go:171","msg":"trace[428343536] linearizableReadLoop","detail":"{readStateIndex:5341; appliedIndex:5338; }","duration":"110.816536ms","start":"2026-04-20T05:00:57.579874Z","end":"2026-04-20T05:00:57.69069Z","steps":["trace[428343536] 'read index received' (duration: 41.104733ms)","trace[428343536] 'applied index is now lower than readState.Index' (duration: 69.710963ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:57.690746Z","caller":"traceutil/trace.go:171","msg":"trace[209059813] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"118.885085ms","start":"2026-04-20T05:00:57.571841Z","end":"2026-04-20T05:00:57.690726Z","steps":["trace[209059813] 'process raft request' (duration: 118.722771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:57.690895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.00416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qwgjj\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-04-20T05:00:57.69093Z","caller":"traceutil/trace.go:171","msg":"trace[1534189564] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qwgjj; range_end:; response_count:1; response_revision:5127; }","duration":"111.082152ms","start":"2026-04-20T05:00:57.579836Z","end":"2026-04-20T05:00:57.690918Z","steps":["trace[1534189564] 'agreement among raft nodes before linearized reading' (duration: 110.955439ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:39.914274Z","caller":"traceutil/trace.go:171","msg":"trace[1658303330] linearizableReadLoop","detail":"{readStateIndex:5781; appliedIndex:5780; }","duration":"121.016396ms","start":"2026-04-20T05:01:39.793237Z","end":"2026-04-20T05:01:39.914253Z","steps":["trace[1658303330] 'read index received' (duration: 54.563683ms)","trace[1658303330] 'applied index is now lower than readState.Index' (duration: 66.451693ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:01:39.914421Z","caller":"traceutil/trace.go:171","msg":"trace[292423898] transaction","detail":"{read_only:false; response_revision:5548; number_of_response:1; }","duration":"124.903247ms","start":"2026-04-20T05:01:39.789444Z","end":"2026-04-20T05:01:39.914347Z","steps":["trace[292423898] 'process raft request' (duration: 102.229325ms)","trace[292423898] 'compare' (duration: 22.455047ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:01:39.91468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.403305ms","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:01:39.914815Z","caller":"traceutil/trace.go:171","msg":"trace[1556619075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5548; }","duration":"121.57263ms","start":"2026-04-20T05:01:39.793209Z","end":"2026-04-20T05:01:39.914782Z","steps":["trace[1556619075] 'agreement among raft nodes before linearized reading' (duration: 121.183081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:39.91506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.464826ms","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-20T05:01:39.915154Z","caller":"traceutil/trace.go:171","msg":"trace[334032709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5548; }","duration":"118.549998ms","start":"2026-04-20T05:01:39.79656Z","end":"2026-04-20T05:01:39.91511Z","steps":["trace[334032709] 'agreement among raft nodes before linearized reading' (duration: 117.826641ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:52.0766Z","caller":"traceutil/trace.go:171","msg":"trace[569250623] transaction","detail":"{read_only:false; response_revision:5656; number_of_response:1; }","duration":"100.701558ms","start":"2026-04-20T05:01:51.975844Z","end":"2026-04-20T05:01:52.076546Z","steps":["trace[569250623] 'process raft request' (duration: 77.618556ms)","trace[569250623] 'compare' (duration: 22.644802ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:04:01.444354Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3308} {"level":"info","ts":"2026-04-20T05:04:01.533733Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3308,"took":"87.833911ms","hash":1471837760,"current-db-size-bytes":60645376,"current-db-size":"61 MB","current-db-size-in-use-bytes":33800192,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-20T05:04:01.533818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1471837760,"revision":3308,"compact-revision":-1} {"level":"info","ts":"2026-04-20T05:06:37.106786Z","caller":"traceutil/trace.go:171","msg":"trace[1494306773] transaction","detail":"{read_only:false; response_revision:8178; number_of_response:1; }","duration":"350.420631ms","start":"2026-04-20T05:06:36.756337Z","end":"2026-04-20T05:06:37.106757Z","steps":["trace[1494306773] 'process raft request' (duration: 350.273048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:37.106952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:36.756318Z","time spent":"350.556404ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:06:37.286395Z","caller":"traceutil/trace.go:171","msg":"trace[562798586] transaction","detail":"{read_only:false; response_revision:8179; number_of_response:1; }","duration":"528.022868ms","start":"2026-04-20T05:06:36.758345Z","end":"2026-04-20T05:06:37.286368Z","steps":["trace[562798586] 'process raft request' (duration: 441.322179ms)","trace[562798586] 'compare' (duration: 86.590167ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:06:37.286583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:36.758329Z","time spent":"528.182672ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:06:43.328089Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002187.wal"} {"level":"info","ts":"2026-04-20T05:06:48.594739Z","caller":"traceutil/trace.go:171","msg":"trace[935384945] transaction","detail":"{read_only:false; response_revision:8255; number_of_response:1; }","duration":"205.165915ms","start":"2026-04-20T05:06:48.389541Z","end":"2026-04-20T05:06:48.594707Z","steps":["trace[935384945] 'process raft request' (duration: 205.012842ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:06:48.841619Z","caller":"traceutil/trace.go:171","msg":"trace[309273394] linearizableReadLoop","detail":"{readStateIndex:8622; appliedIndex:8619; }","duration":"285.071894ms","start":"2026-04-20T05:06:48.556512Z","end":"2026-04-20T05:06:48.841584Z","steps":["trace[309273394] 'read index received' (duration: 38.145867ms)","trace[309273394] 'applied index is now lower than readState.Index' (duration: 246.924877ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:06:48.842085Z","caller":"traceutil/trace.go:171","msg":"trace[366478586] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"414.058628ms","start":"2026-04-20T05:06:48.427991Z","end":"2026-04-20T05:06:48.84205Z","steps":["trace[366478586] 'process raft request' (duration: 413.352411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:48.842145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.620647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-20T05:06:48.842133Z","caller":"traceutil/trace.go:171","msg":"trace[2087804360] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"340.704172ms","start":"2026-04-20T05:06:48.501327Z","end":"2026-04-20T05:06:48.842032Z","steps":["trace[2087804360] 'process raft request' (duration: 340.18258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:48.842258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.560392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T05:06:48.842278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:48.42797Z","time spent":"414.200801ms","remote":"127.0.0.1:54142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16428,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:06:48.842296Z","caller":"traceutil/trace.go:171","msg":"trace[1296971861] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8257; }","duration":"108.628264ms","start":"2026-04-20T05:06:48.73366Z","end":"2026-04-20T05:06:48.842288Z","steps":["trace[1296971861] 'agreement among raft nodes before linearized reading' (duration: 108.565433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:48.842355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:48.501278Z","time spent":"340.965388ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:06:48.842193Z","caller":"traceutil/trace.go:171","msg":"trace[515700722] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8257; }","duration":"285.73418ms","start":"2026-04-20T05:06:48.556445Z","end":"2026-04-20T05:06:48.84218Z","steps":["trace[515700722] 'agreement among raft nodes before linearized reading' (duration: 285.548585ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:06:49.600675Z","caller":"traceutil/trace.go:171","msg":"trace[1617730888] linearizableReadLoop","detail":"{readStateIndex:8628; appliedIndex:8627; }","duration":"229.287573ms","start":"2026-04-20T05:06:49.37136Z","end":"2026-04-20T05:06:49.600647Z","steps":["trace[1617730888] 'read index received' (duration: 229.15619ms)","trace[1617730888] 'applied index is now lower than readState.Index' (duration: 129.963µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:06:49.600821Z","caller":"traceutil/trace.go:171","msg":"trace[1327817689] transaction","detail":"{read_only:false; response_revision:8262; number_of_response:1; }","duration":"430.445904ms","start":"2026-04-20T05:06:49.170324Z","end":"2026-04-20T05:06:49.60077Z","steps":["trace[1327817689] 'process raft request' (duration: 430.125186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:49.600979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.578899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:06:49.601092Z","caller":"traceutil/trace.go:171","msg":"trace[1197287817] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:8262; }","duration":"229.722213ms","start":"2026-04-20T05:06:49.371346Z","end":"2026-04-20T05:06:49.601068Z","steps":["trace[1197287817] 'agreement among raft nodes before linearized reading' (duration: 229.489777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:49.601093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:06:49.170307Z","time spent":"430.618767ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:06:49.701416Z","caller":"traceutil/trace.go:171","msg":"trace[658341233] linearizableReadLoop","detail":"{readStateIndex:8629; appliedIndex:8628; }","duration":"100.594916ms","start":"2026-04-20T05:06:49.60076Z","end":"2026-04-20T05:06:49.701355Z","steps":["trace[658341233] 'read index received' (duration: 65.455279ms)","trace[658341233] 'applied index is now lower than readState.Index' (duration: 35.137477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:06:49.701651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.386478ms","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:49.70174Z","caller":"traceutil/trace.go:171","msg":"trace[1705144206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8262; }","duration":"102.537822ms","start":"2026-04-20T05:06:49.599184Z","end":"2026-04-20T05:06:49.701722Z","steps":["trace[1705144206] 'agreement among raft nodes before linearized reading' (duration: 102.300216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:16.494392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.847528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:07:16.494579Z","caller":"traceutil/trace.go:171","msg":"trace[565274809] linearizableReadLoop","detail":"{readStateIndex:8872; appliedIndex:8871; }","duration":"576.145989ms","start":"2026-04-20T05:07:15.9184Z","end":"2026-04-20T05:07:16.494546Z","steps":["trace[565274809] 'read index received' (duration: 384.964123ms)","trace[565274809] 'applied index is now lower than readState.Index' (duration: 191.180416ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:16.494687Z","caller":"traceutil/trace.go:171","msg":"trace[971184686] transaction","detail":"{read_only:false; response_revision:8494; number_of_response:1; }","duration":"576.355574ms","start":"2026-04-20T05:07:15.918316Z","end":"2026-04-20T05:07:16.494672Z","steps":["trace[971184686] 'process raft request' (duration: 385.118427ms)","trace[971184686] 'compare' (duration: 190.535411ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:07:16.494762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:15.91829Z","time spent":"576.436396ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:07:16.494827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.408615ms","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:07:16.494895Z","caller":"traceutil/trace.go:171","msg":"trace[2025584773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8494; }","duration":"576.506338ms","start":"2026-04-20T05:07:15.918373Z","end":"2026-04-20T05:07:16.494879Z","steps":["trace[2025584773] 'agreement among raft nodes before linearized reading' (duration: 576.347494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:16.494921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:15.918358Z","time spent":"576.558259ms","remote":"127.0.0.1:53960","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:16.494912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.256002ms","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-20T05:07:16.49501Z","caller":"traceutil/trace.go:171","msg":"trace[1543957954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8494; }","duration":"576.376056ms","start":"2026-04-20T05:07:15.918608Z","end":"2026-04-20T05:07:16.494984Z","steps":["trace[1543957954] 'agreement among raft nodes before linearized reading' (duration: 576.13463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:16.49505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.437326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T05:07:16.49506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:15.918601Z","time spent":"576.449517ms","remote":"127.0.0.1:53960","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:16.49519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.034022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:07:16.495209Z","caller":"traceutil/trace.go:171","msg":"trace[273865915] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:8494; }","duration":"134.099694ms","start":"2026-04-20T05:07:16.361104Z","end":"2026-04-20T05:07:16.495204Z","steps":["trace[273865915] 'agreement among raft nodes before linearized reading' (duration: 134.069153ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:16.49507Z","caller":"traceutil/trace.go:171","msg":"trace[1546833369] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:8494; }","duration":"178.487697ms","start":"2026-04-20T05:07:16.316577Z","end":"2026-04-20T05:07:16.495065Z","steps":["trace[1546833369] 'agreement among raft nodes before linearized reading' (duration: 178.453087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:16.49532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.786156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qwgjj\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-04-20T05:07:16.495392Z","caller":"traceutil/trace.go:171","msg":"trace[2086498937] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qwgjj; range_end:; response_count:1; response_revision:8494; }","duration":"491.93811ms","start":"2026-04-20T05:07:16.003437Z","end":"2026-04-20T05:07:16.495375Z","steps":["trace[2086498937] 'agreement among raft nodes before linearized reading' (duration: 491.736455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:16.495614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.102882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-20T05:07:16.495636Z","caller":"traceutil/trace.go:171","msg":"trace[528587638] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8494; }","duration":"415.147793ms","start":"2026-04-20T05:07:16.080481Z","end":"2026-04-20T05:07:16.495629Z","steps":["trace[528587638] 'agreement among raft nodes before linearized reading' (duration: 415.093401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:16.495656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:16.08047Z","time spent":"415.182154ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T05:07:16.495633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:16.003419Z","time spent":"492.197245ms","remote":"127.0.0.1:55062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-qwgjj\" "} {"level":"info","ts":"2026-04-20T05:07:29.647263Z","caller":"traceutil/trace.go:171","msg":"trace[624697465] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"109.064995ms","start":"2026-04-20T05:07:29.538159Z","end":"2026-04-20T05:07:29.647224Z","steps":["trace[624697465] 'process raft request' (duration: 102.515631ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:30.303247Z","caller":"traceutil/trace.go:171","msg":"trace[1766459293] linearizableReadLoop","detail":"{readStateIndex:9015; appliedIndex:9014; }","duration":"346.423117ms","start":"2026-04-20T05:07:29.956787Z","end":"2026-04-20T05:07:30.30321Z","steps":["trace[1766459293] 'read index received' (duration: 346.090399ms)","trace[1766459293] 'applied index is now lower than readState.Index' (duration: 330.498µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:30.303374Z","caller":"traceutil/trace.go:171","msg":"trace[118427455] transaction","detail":"{read_only:false; response_revision:8627; number_of_response:1; }","duration":"349.51174ms","start":"2026-04-20T05:07:29.953817Z","end":"2026-04-20T05:07:30.303329Z","steps":["trace[118427455] 'process raft request' (duration: 349.08942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:30.303562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.749215ms","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:07:30.303664Z","caller":"traceutil/trace.go:171","msg":"trace[408796798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8627; }","duration":"346.903428ms","start":"2026-04-20T05:07:29.956738Z","end":"2026-04-20T05:07:30.303642Z","steps":["trace[408796798] 'agreement among raft nodes before linearized reading' (duration: 346.651172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:30.303716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:29.956715Z","time spent":"346.991951ms","remote":"127.0.0.1:53960","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:07:30.303984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:29.953787Z","time spent":"349.821707ms","remote":"127.0.0.1:54142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16428,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:07:30.424711Z","caller":"traceutil/trace.go:171","msg":"trace[2051326809] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"116.961001ms","start":"2026-04-20T05:07:30.307719Z","end":"2026-04-20T05:07:30.42468Z","steps":["trace[2051326809] 'process raft request' (duration: 93.99021ms)","trace[2051326809] 'compare' (duration: 22.846308ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:30.445256Z","caller":"traceutil/trace.go:171","msg":"trace[517964752] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8628; }","duration":"134.525954ms","start":"2026-04-20T05:07:30.310689Z","end":"2026-04-20T05:07:30.445215Z","steps":["trace[517964752] 'process raft request' (duration: 134.308528ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:30.445303Z","caller":"traceutil/trace.go:171","msg":"trace[1198930094] transaction","detail":"{read_only:false; response_revision:8629; number_of_response:1; }","duration":"132.806223ms","start":"2026-04-20T05:07:30.312466Z","end":"2026-04-20T05:07:30.445272Z","steps":["trace[1198930094] 'process raft request' (duration: 132.623119ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:30.4455Z","caller":"traceutil/trace.go:171","msg":"trace[1549131009] transaction","detail":"{read_only:false; response_revision:8630; number_of_response:1; }","duration":"127.402446ms","start":"2026-04-20T05:07:30.318049Z","end":"2026-04-20T05:07:30.445452Z","steps":["trace[1549131009] 'process raft request' (duration: 127.1378ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:30.445494Z","caller":"traceutil/trace.go:171","msg":"trace[788690213] linearizableReadLoop","detail":"{readStateIndex:9018; appliedIndex:9015; }","duration":"128.009851ms","start":"2026-04-20T05:07:30.317431Z","end":"2026-04-20T05:07:30.44544Z","steps":["trace[788690213] 'read index received' (duration: 84.282963ms)","trace[788690213] 'applied index is now lower than readState.Index' (duration: 43.724428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:07:30.445772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.324978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-cq748\" ","response":"range_response_count:1 size:4614"} {"level":"info","ts":"2026-04-20T05:07:30.445842Z","caller":"traceutil/trace.go:171","msg":"trace[2139864161] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-cq748; range_end:; response_count:1; response_revision:8630; }","duration":"128.446021ms","start":"2026-04-20T05:07:30.317379Z","end":"2026-04-20T05:07:30.445825Z","steps":["trace[2139864161] 'agreement among raft nodes before linearized reading' (duration: 128.165784ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:31.620079Z","caller":"traceutil/trace.go:171","msg":"trace[291432667] transaction","detail":"{read_only:false; response_revision:8639; number_of_response:1; }","duration":"137.383251ms","start":"2026-04-20T05:07:31.482669Z","end":"2026-04-20T05:07:31.620052Z","steps":["trace[291432667] 'process raft request' (duration: 137.227478ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:46.933884Z","caller":"traceutil/trace.go:171","msg":"trace[597500563] linearizableReadLoop","detail":"{readStateIndex:9238; appliedIndex:9237; }","duration":"257.498046ms","start":"2026-04-20T05:07:46.676365Z","end":"2026-04-20T05:07:46.933863Z","steps":["trace[597500563] 'read index received' (duration: 257.446304ms)","trace[597500563] 'applied index is now lower than readState.Index' (duration: 50.632µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:46.933947Z","caller":"traceutil/trace.go:171","msg":"trace[413999599] transaction","detail":"{read_only:false; response_revision:8839; number_of_response:1; }","duration":"408.732392ms","start":"2026-04-20T05:07:46.525201Z","end":"2026-04-20T05:07:46.933934Z","steps":["trace[413999599] 'process raft request' (duration: 408.522637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:46.93403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:46.525181Z","time spent":"408.796984ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:07:46.934003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.627069ms","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:46.934209Z","caller":"traceutil/trace.go:171","msg":"trace[1290078706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8839; }","duration":"257.872774ms","start":"2026-04-20T05:07:46.676328Z","end":"2026-04-20T05:07:46.9342Z","steps":["trace[1290078706] 'agreement among raft nodes before linearized reading' (duration: 257.640629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:46.93919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.611809ms","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-20T05:07:46.939242Z","caller":"traceutil/trace.go:171","msg":"trace[1890703866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8840; }","duration":"206.688881ms","start":"2026-04-20T05:07:46.73254Z","end":"2026-04-20T05:07:46.939229Z","steps":["trace[1890703866] 'agreement among raft nodes before linearized reading' (duration: 206.499206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:46.939271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.578966ms","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:46.939271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.834145ms","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:07:46.939353Z","caller":"traceutil/trace.go:171","msg":"trace[693437584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8840; }","duration":"228.701978ms","start":"2026-04-20T05:07:46.710634Z","end":"2026-04-20T05:07:46.939336Z","steps":["trace[693437584] 'agreement among raft nodes before linearized reading' (duration: 228.514854ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:46.93937Z","caller":"traceutil/trace.go:171","msg":"trace[1439851726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8840; }","duration":"209.962468ms","start":"2026-04-20T05:07:46.729386Z","end":"2026-04-20T05:07:46.939348Z","steps":["trace[1439851726] 'agreement among raft nodes before linearized reading' (duration: 209.715563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:46.939587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.376423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qwgjj\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-04-20T05:07:46.939692Z","caller":"traceutil/trace.go:171","msg":"trace[1718301993] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qwgjj; range_end:; response_count:1; response_revision:8840; }","duration":"203.516396ms","start":"2026-04-20T05:07:46.736151Z","end":"2026-04-20T05:07:46.939667Z","steps":["trace[1718301993] 'agreement among raft nodes before linearized reading' (duration: 203.195668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:46.939734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.841105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-glance\" ","response":"range_response_count:1 size:3188"} {"level":"info","ts":"2026-04-20T05:07:46.939856Z","caller":"traceutil/trace.go:171","msg":"trace[486592785] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-glance; range_end:; response_count:1; response_revision:8840; }","duration":"247.009799ms","start":"2026-04-20T05:07:46.692836Z","end":"2026-04-20T05:07:46.939846Z","steps":["trace[486592785] 'agreement among raft nodes before linearized reading' (duration: 246.229091ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:09:01.47678Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fe334179879fd0aa","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T05:09:01.481797Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6638} {"level":"info","ts":"2026-04-20T05:09:01.483092Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T05:09:01.483218Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T05:09:01.573077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6638,"took":"89.185827ms","hash":349695829,"current-db-size-bytes":60645376,"current-db-size":"61 MB","current-db-size-in-use-bytes":27037696,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-20T05:09:01.573139Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":349695829,"revision":6638,"compact-revision":3308} {"level":"info","ts":"2026-04-20T05:14:01.491946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9570} {"level":"info","ts":"2026-04-20T05:14:01.587491Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9570,"took":"91.971894ms","hash":1083144386,"current-db-size-bytes":60645376,"current-db-size":"61 MB","current-db-size-in-use-bytes":25538560,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-20T05:14:01.587592Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1083144386,"revision":9570,"compact-revision":6638}