{"level":"warn","ts":"2026-04-23T13:31:29.827922Z","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-23T13:31:29.82807Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-23T13:31:29.828209Z","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-23T13:31:29.828225Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-23T13:31:29.828266Z","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-23T13:31:29.829019Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-23T13:31:29.829148Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-23T13:31:29.834302Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.857593ms"} {"level":"info","ts":"2026-04-23T13:31:29.843501Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-23T13:31:29.84367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T13:31:29.843771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-23T13:31:29.843802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T13:31:29.843816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-23T13:31:29.843941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-23T13:31:29.852018Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T13:31:29.854085Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T13:31:29.855121Z","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-23T13:31:29.857342Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T13:31:29.857593Z","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-23T13:31:29.857633Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T13:31:29.85787Z","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-23T13:31:29.857967Z","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-23T13:31:29.858363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-23T13:31:29.859144Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-23T13:31:29.863174Z","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-23T13:31:29.863409Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-23T13:31:29.86353Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-23T13:31:29.863661Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T13:31:29.863578Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T13:31:30.245263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T13:31:30.245364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T13:31:30.245395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-23T13:31:30.24542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-23T13:31:30.245445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-23T13:31:30.245488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-23T13:31:30.245514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-23T13:31:30.246348Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T13:31:30.247167Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T13:31:30.247234Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T13:31:30.247167Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T13:31:30.24758Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T13:31:30.247674Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T13:31:30.24743Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T13:31:30.247729Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T13:31:30.24771Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T13:31:30.249748Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T13:31:30.251693Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-04-23T13:31:34.926382Z","caller":"traceutil/trace.go:171","msg":"trace[478406460] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"125.0583ms","start":"2026-04-23T13:31:34.801306Z","end":"2026-04-23T13:31:34.926364Z","steps":["trace[478406460] 'process raft request' (duration: 124.969988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:31:35.169634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.735044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T13:31:35.16976Z","caller":"traceutil/trace.go:171","msg":"trace[1309153354] linearizableReadLoop","detail":"{readStateIndex:286; appliedIndex:285; }","duration":"243.245814ms","start":"2026-04-23T13:31:34.926498Z","end":"2026-04-23T13:31:35.169744Z","steps":["trace[1309153354] 'read index received' (duration: 141.03148ms)","trace[1309153354] 'applied index is now lower than readState.Index' (duration: 102.213124ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:31:35.169865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.774386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-23T13:31:35.169901Z","caller":"traceutil/trace.go:171","msg":"trace[181756516] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:281; }","duration":"292.845157ms","start":"2026-04-23T13:31:34.877045Z","end":"2026-04-23T13:31:35.16989Z","steps":["trace[181756516] 'agreement among raft nodes before linearized reading' (duration: 292.745465ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:31:35.169903Z","caller":"traceutil/trace.go:171","msg":"trace[2024636506] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"293.416209ms","start":"2026-04-23T13:31:34.876416Z","end":"2026-04-23T13:31:35.169832Z","steps":["trace[2024636506] 'process raft request' (duration: 191.029932ms)","trace[2024636506] 'compare' (duration: 101.622792ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:31:35.170102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.978232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:31:35.170199Z","caller":"traceutil/trace.go:171","msg":"trace[581426547] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:281; }","duration":"290.113774ms","start":"2026-04-23T13:31:34.880062Z","end":"2026-04-23T13:31:35.170176Z","steps":["trace[581426547] 'agreement among raft nodes before linearized reading' (duration: 289.91368ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:31:35.372043Z","caller":"traceutil/trace.go:171","msg":"trace[1455459615] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"439.905152ms","start":"2026-04-23T13:31:34.9321Z","end":"2026-04-23T13:31:35.372006Z","steps":["trace[1455459615] 'process raft request' (duration: 439.684478ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:31:35.372049Z","caller":"traceutil/trace.go:171","msg":"trace[1794923058] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"439.928052ms","start":"2026-04-23T13:31:34.932054Z","end":"2026-04-23T13:31:35.371982Z","steps":["trace[1794923058] 'process raft request' (duration: 415.475366ms)","trace[1794923058] 'compare' (duration: 24.138941ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:31:35.372201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:31:34.93208Z","time spent":"440.044215ms","remote":"127.0.0.1:60586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":373,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T13:31:35.372266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:31:34.932018Z","time spent":"440.149497ms","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T13:31:35.372265Z","caller":"traceutil/trace.go:171","msg":"trace[1844877907] transaction","detail":"{read_only:false; number_of_response:0; response_revision:283; }","duration":"389.596794ms","start":"2026-04-23T13:31:34.982643Z","end":"2026-04-23T13:31:35.372239Z","steps":["trace[1844877907] 'process raft request' (duration: 389.196127ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:31:35.372298Z","caller":"traceutil/trace.go:171","msg":"trace[1272435884] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"198.044726ms","start":"2026-04-23T13:31:35.174227Z","end":"2026-04-23T13:31:35.372272Z","steps":["trace[1272435884] 'process raft request' (duration: 197.980715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:31:35.372375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:31:34.982618Z","time spent":"389.704976ms","remote":"127.0.0.1:60414","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T13:31:35.372605Z","caller":"traceutil/trace.go:171","msg":"trace[624054181] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"375.538782ms","start":"2026-04-23T13:31:34.997056Z","end":"2026-04-23T13:31:35.372594Z","steps":["trace[624054181] 'process raft request' (duration: 374.831988ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:31:35.37263Z","caller":"traceutil/trace.go:171","msg":"trace[1752676115] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"374.893738ms","start":"2026-04-23T13:31:34.997727Z","end":"2026-04-23T13:31:35.372621Z","steps":["trace[1752676115] 'process raft request' (duration: 374.410858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:31:35.372666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:31:34.997721Z","time spent":"374.928089ms","remote":"127.0.0.1:60416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T13:31:35.372691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:31:34.997037Z","time spent":"375.594793ms","remote":"127.0.0.1:60416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T13:31:35.372704Z","caller":"traceutil/trace.go:171","msg":"trace[1965549130] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:286; }","duration":"199.448285ms","start":"2026-04-23T13:31:35.173239Z","end":"2026-04-23T13:31:35.372687Z","steps":["trace[1965549130] 'read index received' (duration: 174.302574ms)","trace[1965549130] 'applied index is now lower than readState.Index' (duration: 25.144051ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:31:35.372751Z","caller":"traceutil/trace.go:171","msg":"trace[563339299] transaction","detail":"{read_only:false; number_of_response:0; response_revision:284; }","duration":"375.600922ms","start":"2026-04-23T13:31:34.997144Z","end":"2026-04-23T13:31:35.372745Z","steps":["trace[563339299] 'process raft request' (duration: 374.814236ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:31:35.372817Z","caller":"traceutil/trace.go:171","msg":"trace[157937635] transaction","detail":"{read_only:false; number_of_response:0; response_revision:286; }","duration":"374.753166ms","start":"2026-04-23T13:31:34.998055Z","end":"2026-04-23T13:31:35.372809Z","steps":["trace[157937635] 'process raft request' (duration: 374.119783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:31:35.372851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:31:34.997136Z","time spent":"375.696413ms","remote":"127.0.0.1:60416","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T13:31:35.372896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:31:34.998045Z","time spent":"374.795446ms","remote":"127.0.0.1:60416","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T13:31:35.372968Z","caller":"traceutil/trace.go:171","msg":"trace[1578257046] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"375.661833ms","start":"2026-04-23T13:31:34.997287Z","end":"2026-04-23T13:31:35.372949Z","steps":["trace[1578257046] 'process raft request' (duration: 374.712985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:31:35.373038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:31:34.997281Z","time spent":"375.721924ms","remote":"127.0.0.1:60416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T13:31:35.373062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.807991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"warn","ts":"2026-04-23T13:31:35.373094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.713139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-23T13:31:35.373132Z","caller":"traceutil/trace.go:171","msg":"trace[2122204150] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:287; }","duration":"199.780591ms","start":"2026-04-23T13:31:35.173343Z","end":"2026-04-23T13:31:35.373123Z","steps":["trace[2122204150] 'agreement among raft nodes before linearized reading' (duration: 199.633638ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:31:35.373134Z","caller":"traceutil/trace.go:171","msg":"trace[122579788] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:287; }","duration":"199.916773ms","start":"2026-04-23T13:31:35.173204Z","end":"2026-04-23T13:31:35.373121Z","steps":["trace[122579788] 'agreement among raft nodes before linearized reading' (duration: 199.78594ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:32:00.236448Z","caller":"traceutil/trace.go:171","msg":"trace[398017492] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"139.297229ms","start":"2026-04-23T13:32:00.097112Z","end":"2026-04-23T13:32:00.236409Z","steps":["trace[398017492] 'process raft request' (duration: 58.687078ms)","trace[398017492] 'compare' (duration: 80.235136ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:32:05.616863Z","caller":"traceutil/trace.go:171","msg":"trace[1939956997] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"102.79345ms","start":"2026-04-23T13:32:05.514044Z","end":"2026-04-23T13:32:05.616838Z","steps":["trace[1939956997] 'process raft request' (duration: 94.246027ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:32:06.990975Z","caller":"traceutil/trace.go:171","msg":"trace[2056048928] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"320.742693ms","start":"2026-04-23T13:32:06.670215Z","end":"2026-04-23T13:32:06.990958Z","steps":["trace[2056048928] 'read index received' (duration: 320.062514ms)","trace[2056048928] 'applied index is now lower than readState.Index' (duration: 677.869µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:32:06.991027Z","caller":"traceutil/trace.go:171","msg":"trace[1137434846] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"370.294828ms","start":"2026-04-23T13:32:06.620719Z","end":"2026-04-23T13:32:06.991014Z","steps":["trace[1137434846] 'process raft request' (duration: 369.769031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:32:06.991088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.862515ms","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-23T13:32:06.991123Z","caller":"traceutil/trace.go:171","msg":"trace[622256908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"320.921735ms","start":"2026-04-23T13:32:06.670182Z","end":"2026-04-23T13:32:06.991104Z","steps":["trace[622256908] 'agreement among raft nodes before linearized reading' (duration: 320.846034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:32:06.991145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:32:06.670166Z","time spent":"320.973175ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T13:32:06.991252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:32:06.620694Z","time spent":"370.367328ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:33:15.598968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.44515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-23T13:33:15.599087Z","caller":"traceutil/trace.go:171","msg":"trace[2134321184] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1448; }","duration":"120.660167ms","start":"2026-04-23T13:33:15.478401Z","end":"2026-04-23T13:33:15.599062Z","steps":["trace[2134321184] 'range keys from in-memory index tree' (duration: 120.280514ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:33:39.796028Z","caller":"traceutil/trace.go:171","msg":"trace[2005171546] linearizableReadLoop","detail":"{readStateIndex:1893; appliedIndex:1892; }","duration":"340.2738ms","start":"2026-04-23T13:33:39.45573Z","end":"2026-04-23T13:33:39.796004Z","steps":["trace[2005171546] 'read index received' (duration: 340.159846ms)","trace[2005171546] 'applied index is now lower than readState.Index' (duration: 112.984µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:33:39.796051Z","caller":"traceutil/trace.go:171","msg":"trace[820969228] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"342.443922ms","start":"2026-04-23T13:33:39.45358Z","end":"2026-04-23T13:33:39.796024Z","steps":["trace[820969228] 'process raft request' (duration: 342.273827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:33:39.796195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.176396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e7c6dc2e-cb7e-4a4b-9299-ba087d073eef\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-23T13:33:39.796231Z","caller":"traceutil/trace.go:171","msg":"trace[909828563] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e7c6dc2e-cb7e-4a4b-9299-ba087d073eef; range_end:; response_count:1; response_revision:1843; }","duration":"144.28203ms","start":"2026-04-23T13:33:39.651937Z","end":"2026-04-23T13:33:39.796219Z","steps":["trace[909828563] 'agreement among raft nodes before linearized reading' (duration: 144.204618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:33:39.796225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.480175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-23T13:33:39.796243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:33:39.45356Z","time spent":"342.571996ms","remote":"127.0.0.1:60416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:33:39.796388Z","caller":"traceutil/trace.go:171","msg":"trace[891853769] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1843; }","duration":"340.565967ms","start":"2026-04-23T13:33:39.455712Z","end":"2026-04-23T13:33:39.796278Z","steps":["trace[891853769] 'agreement among raft nodes before linearized reading' (duration: 340.349791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:33:39.796447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:33:39.455703Z","time spent":"340.728633ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T13:33:40.054301Z","caller":"traceutil/trace.go:171","msg":"trace[16901170] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"107.448427ms","start":"2026-04-23T13:33:39.946789Z","end":"2026-04-23T13:33:40.054238Z","steps":["trace[16901170] 'process raft request' (duration: 107.253771ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:34:12.906936Z","caller":"traceutil/trace.go:171","msg":"trace[231166038] linearizableReadLoop","detail":"{readStateIndex:2180; appliedIndex:2179; }","duration":"191.614755ms","start":"2026-04-23T13:34:12.715289Z","end":"2026-04-23T13:34:12.906904Z","steps":["trace[231166038] 'read index received' (duration: 105.98963ms)","trace[231166038] 'applied index is now lower than readState.Index' (duration: 85.624035ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:34:12.907057Z","caller":"traceutil/trace.go:171","msg":"trace[2141316241] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"223.092155ms","start":"2026-04-23T13:34:12.683955Z","end":"2026-04-23T13:34:12.907047Z","steps":["trace[2141316241] 'process raft request' (duration: 137.498501ms)","trace[2141316241] 'compare' (duration: 84.95906ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:34:12.907274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.979284ms","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-23T13:34:12.907304Z","caller":"traceutil/trace.go:171","msg":"trace[1719887933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2117; }","duration":"192.057735ms","start":"2026-04-23T13:34:12.715238Z","end":"2026-04-23T13:34:12.907296Z","steps":["trace[1719887933] 'agreement among raft nodes before linearized reading' (duration: 191.942932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:34:12.907463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.951796ms","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-23T13:34:12.907559Z","caller":"traceutil/trace.go:171","msg":"trace[929960485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2117; }","duration":"186.057899ms","start":"2026-04-23T13:34:12.721487Z","end":"2026-04-23T13:34:12.907545Z","steps":["trace[929960485] 'agreement among raft nodes before linearized reading' (duration: 185.926886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:34:12.907775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.433718ms","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-23T13:34:12.907799Z","caller":"traceutil/trace.go:171","msg":"trace[1501855862] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2117; }","duration":"105.457108ms","start":"2026-04-23T13:34:12.802333Z","end":"2026-04-23T13:34:12.90779Z","steps":["trace[1501855862] 'agreement among raft nodes before linearized reading' (duration: 105.390297ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:34:13.163448Z","caller":"traceutil/trace.go:171","msg":"trace[1316318042] linearizableReadLoop","detail":"{readStateIndex:2183; appliedIndex:2181; }","duration":"230.927642ms","start":"2026-04-23T13:34:12.932495Z","end":"2026-04-23T13:34:13.163422Z","steps":["trace[1316318042] 'read index received' (duration: 41.809425ms)","trace[1316318042] 'applied index is now lower than readState.Index' (duration: 189.117117ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:34:13.163563Z","caller":"traceutil/trace.go:171","msg":"trace[723442542] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"252.050093ms","start":"2026-04-23T13:34:12.911502Z","end":"2026-04-23T13:34:13.163552Z","steps":["trace[723442542] 'process raft request' (duration: 238.680059ms)","trace[723442542] 'compare' (duration: 12.699468ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:34:13.163842Z","caller":"traceutil/trace.go:171","msg":"trace[846255034] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"250.954469ms","start":"2026-04-23T13:34:12.912857Z","end":"2026-04-23T13:34:13.163812Z","steps":["trace[846255034] 'process raft request' (duration: 250.514499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:34:13.163876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.359101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-23T13:34:13.164028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.876579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a8ffbda9726752\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-23T13:34:13.164191Z","caller":"traceutil/trace.go:171","msg":"trace[993724418] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a8ffbda9726752; range_end:; response_count:1; response_revision:2120; }","duration":"112.024163ms","start":"2026-04-23T13:34:13.052093Z","end":"2026-04-23T13:34:13.164117Z","steps":["trace[993724418] 'agreement among raft nodes before linearized reading' (duration: 111.733196ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:34:13.164368Z","caller":"traceutil/trace.go:171","msg":"trace[1694254098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2120; }","duration":"231.614887ms","start":"2026-04-23T13:34:12.932457Z","end":"2026-04-23T13:34:13.164072Z","steps":["trace[1694254098] 'agreement among raft nodes before linearized reading' (duration: 231.171877ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:34:13.371956Z","caller":"traceutil/trace.go:171","msg":"trace[487401818] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"205.193268ms","start":"2026-04-23T13:34:13.166732Z","end":"2026-04-23T13:34:13.371926Z","steps":["trace[487401818] 'process raft request' (duration: 197.995865ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:34:13.372021Z","caller":"traceutil/trace.go:171","msg":"trace[1302353613] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2183; }","duration":"204.663445ms","start":"2026-04-23T13:34:13.167348Z","end":"2026-04-23T13:34:13.372011Z","steps":["trace[1302353613] 'read index received' (duration: 197.393611ms)","trace[1302353613] 'applied index is now lower than readState.Index' (duration: 7.268314ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:34:13.371978Z","caller":"traceutil/trace.go:171","msg":"trace[2147185227] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"202.748832ms","start":"2026-04-23T13:34:13.169213Z","end":"2026-04-23T13:34:13.371962Z","steps":["trace[2147185227] 'process raft request' (duration: 202.687631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:34:13.372555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.719272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-04-23T13:34:13.372558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.428117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-23T13:34:13.372594Z","caller":"traceutil/trace.go:171","msg":"trace[230235668] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2122; }","duration":"143.816314ms","start":"2026-04-23T13:34:13.228768Z","end":"2026-04-23T13:34:13.372584Z","steps":["trace[230235668] 'agreement among raft nodes before linearized reading' (duration: 143.60614ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:34:13.372624Z","caller":"traceutil/trace.go:171","msg":"trace[1046722259] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:2122; }","duration":"177.55874ms","start":"2026-04-23T13:34:13.195045Z","end":"2026-04-23T13:34:13.372604Z","steps":["trace[1046722259] 'agreement among raft nodes before linearized reading' (duration: 177.339885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:34:13.372617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.67258ms","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":"info","ts":"2026-04-23T13:34:13.372728Z","caller":"traceutil/trace.go:171","msg":"trace[266575581] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2122; }","duration":"202.842174ms","start":"2026-04-23T13:34:13.16986Z","end":"2026-04-23T13:34:13.372702Z","steps":["trace[266575581] 'agreement among raft nodes before linearized reading' (duration: 202.473876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:34:13.372723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.345171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-23T13:34:13.37281Z","caller":"traceutil/trace.go:171","msg":"trace[198449084] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2122; }","duration":"205.483894ms","start":"2026-04-23T13:34:13.167302Z","end":"2026-04-23T13:34:13.372786Z","steps":["trace[198449084] 'agreement among raft nodes before linearized reading' (duration: 205.200787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:35:43.564133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.857436ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-04-23T13:35:43.564247Z","caller":"traceutil/trace.go:171","msg":"trace[1176761591] linearizableReadLoop","detail":"{readStateIndex:2935; appliedIndex:2934; }","duration":"136.807469ms","start":"2026-04-23T13:35:43.427423Z","end":"2026-04-23T13:35:43.56423Z","steps":["trace[1176761591] 'read index received' (duration: 6.819871ms)","trace[1176761591] 'applied index is now lower than readState.Index' (duration: 129.985348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:35:43.564396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.946311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T13:35:43.564481Z","caller":"traceutil/trace.go:171","msg":"trace[2114550877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2832; }","duration":"137.090433ms","start":"2026-04-23T13:35:43.42737Z","end":"2026-04-23T13:35:43.564461Z","steps":["trace[2114550877] 'agreement among raft nodes before linearized reading' (duration: 136.933281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:35:43.564806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.834316ms","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-23T13:35:43.564858Z","caller":"traceutil/trace.go:171","msg":"trace[569627884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2832; }","duration":"129.931626ms","start":"2026-04-23T13:35:43.434912Z","end":"2026-04-23T13:35:43.564844Z","steps":["trace[569627884] 'agreement among raft nodes before linearized reading' (duration: 129.743333ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:35:43.719683Z","caller":"traceutil/trace.go:171","msg":"trace[80083386] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"150.280386ms","start":"2026-04-23T13:35:43.569381Z","end":"2026-04-23T13:35:43.719662Z","steps":["trace[80083386] 'process raft request' (duration: 134.509072ms)","trace[80083386] 'compare' (duration: 15.55331ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:35:43.719582Z","caller":"traceutil/trace.go:171","msg":"trace[1305678398] linearizableReadLoop","detail":"{readStateIndex:2937; appliedIndex:2936; }","duration":"117.452926ms","start":"2026-04-23T13:35:43.602107Z","end":"2026-04-23T13:35:43.71956Z","steps":["trace[1305678398] 'read index received' (duration: 101.748643ms)","trace[1305678398] 'applied index is now lower than readState.Index' (duration: 15.703093ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:35:43.720041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.911314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-23T13:35:43.720149Z","caller":"traceutil/trace.go:171","msg":"trace[1699093027] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2834; }","duration":"118.082647ms","start":"2026-04-23T13:35:43.602047Z","end":"2026-04-23T13:35:43.72013Z","steps":["trace[1699093027] 'agreement among raft nodes before linearized reading' (duration: 117.855394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:35:43.720387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.080161ms","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-23T13:35:43.720467Z","caller":"traceutil/trace.go:171","msg":"trace[1802758867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2834; }","duration":"109.216453ms","start":"2026-04-23T13:35:43.611242Z","end":"2026-04-23T13:35:43.720459Z","steps":["trace[1802758867] 'agreement among raft nodes before linearized reading' (duration: 108.724576ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:35:43.891974Z","caller":"traceutil/trace.go:171","msg":"trace[1288235155] linearizableReadLoop","detail":"{readStateIndex:2938; appliedIndex:2937; }","duration":"167.330011ms","start":"2026-04-23T13:35:43.724601Z","end":"2026-04-23T13:35:43.891931Z","steps":["trace[1288235155] 'read index received' (duration: 167.027966ms)","trace[1288235155] 'applied index is now lower than readState.Index' (duration: 300.725µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:35:43.892242Z","caller":"traceutil/trace.go:171","msg":"trace[193796432] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"167.937681ms","start":"2026-04-23T13:35:43.724197Z","end":"2026-04-23T13:35:43.892135Z","steps":["trace[193796432] 'process raft request' (duration: 167.517095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:35:43.892359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.723567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-23T13:35:43.89243Z","caller":"traceutil/trace.go:171","msg":"trace[1626332850] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"164.879671ms","start":"2026-04-23T13:35:43.727525Z","end":"2026-04-23T13:35:43.892405Z","steps":["trace[1626332850] 'process raft request' (duration: 164.688788ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:35:43.892423Z","caller":"traceutil/trace.go:171","msg":"trace[334094296] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2835; }","duration":"167.807119ms","start":"2026-04-23T13:35:43.724587Z","end":"2026-04-23T13:35:43.892394Z","steps":["trace[334094296] 'agreement among raft nodes before linearized reading' (duration: 167.468614ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:36:45.658059Z","caller":"traceutil/trace.go:171","msg":"trace[309647690] transaction","detail":"{read_only:false; response_revision:3346; number_of_response:1; }","duration":"122.295574ms","start":"2026-04-23T13:36:45.535739Z","end":"2026-04-23T13:36:45.658034Z","steps":["trace[309647690] 'process raft request' (duration: 118.292554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:37:19.079303Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409924763997240,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T13:37:19.095106Z","caller":"traceutil/trace.go:171","msg":"trace[867357510] transaction","detail":"{read_only:false; response_revision:3592; number_of_response:1; }","duration":"516.774277ms","start":"2026-04-23T13:37:18.578297Z","end":"2026-04-23T13:37:19.095072Z","steps":["trace[867357510] 'process raft request' (duration: 516.591378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:37:19.095434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:37:18.578286Z","time spent":"517.03003ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:37:19.095756Z","caller":"traceutil/trace.go:171","msg":"trace[1686522558] linearizableReadLoop","detail":"{readStateIndex:3741; appliedIndex:3737; }","duration":"517.519076ms","start":"2026-04-23T13:37:18.578225Z","end":"2026-04-23T13:37:19.095744Z","steps":["trace[1686522558] 'read index received' (duration: 504.207105ms)","trace[1686522558] 'applied index is now lower than readState.Index' (duration: 13.310871ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:37:19.096109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.866413ms","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-23T13:37:19.096159Z","caller":"traceutil/trace.go:171","msg":"trace[283894156] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3594; }","duration":"517.955687ms","start":"2026-04-23T13:37:18.578188Z","end":"2026-04-23T13:37:19.096144Z","steps":["trace[283894156] 'agreement among raft nodes before linearized reading' (duration: 517.762807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:37:19.096223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.865013ms","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-23T13:37:19.096265Z","caller":"traceutil/trace.go:171","msg":"trace[755231629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3594; }","duration":"517.941506ms","start":"2026-04-23T13:37:18.578308Z","end":"2026-04-23T13:37:19.09625Z","steps":["trace[755231629] 'agreement among raft nodes before linearized reading' (duration: 517.838061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:37:19.0963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:37:18.578282Z","time spent":"518.01101ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T13:37:19.096333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:37:18.578173Z","time spent":"518.079133ms","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":666,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-23T13:37:19.096472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.256033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T13:37:19.096501Z","caller":"traceutil/trace.go:171","msg":"trace[19919666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3594; }","duration":"518.313275ms","start":"2026-04-23T13:37:18.578178Z","end":"2026-04-23T13:37:19.096492Z","steps":["trace[19919666] 'agreement among raft nodes before linearized reading' (duration: 518.229041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:37:19.096528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:37:18.578162Z","time spent":"518.358277ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T13:37:19.096856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.249851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T13:37:19.096972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.264823ms","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-23T13:37:19.097008Z","caller":"traceutil/trace.go:171","msg":"trace[766534239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3594; }","duration":"517.332385ms","start":"2026-04-23T13:37:18.579667Z","end":"2026-04-23T13:37:19.096999Z","steps":["trace[766534239] 'agreement among raft nodes before linearized reading' (duration: 517.197679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:37:19.097037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:37:18.579662Z","time spent":"517.368208ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T13:37:19.096975Z","caller":"traceutil/trace.go:171","msg":"trace[1398610762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3594; }","duration":"515.375326ms","start":"2026-04-23T13:37:18.581584Z","end":"2026-04-23T13:37:19.096959Z","steps":["trace[1398610762] 'agreement among raft nodes before linearized reading' (duration: 515.235829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:37:19.097196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.786029ms","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-23T13:37:19.097273Z","caller":"traceutil/trace.go:171","msg":"trace[920562520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3594; }","duration":"517.872863ms","start":"2026-04-23T13:37:18.579391Z","end":"2026-04-23T13:37:19.097263Z","steps":["trace[920562520] 'agreement among raft nodes before linearized reading' (duration: 517.746846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:37:19.097318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:37:18.579384Z","time spent":"517.920955ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T13:37:19.097272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:37:18.581579Z","time spent":"515.568316ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T13:37:19.097778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.384066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T13:37:19.09563Z","caller":"traceutil/trace.go:171","msg":"trace[791774128] transaction","detail":"{read_only:false; response_revision:3593; number_of_response:1; }","duration":"517.295695ms","start":"2026-04-23T13:37:18.578306Z","end":"2026-04-23T13:37:19.095602Z","steps":["trace[791774128] 'process raft request' (duration: 516.727655ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:37:19.097839Z","caller":"traceutil/trace.go:171","msg":"trace[1684710495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3594; }","duration":"106.474561ms","start":"2026-04-23T13:37:18.99135Z","end":"2026-04-23T13:37:19.097825Z","steps":["trace[1684710495] 'agreement among raft nodes before linearized reading' (duration: 106.308522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:37:19.098015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:37:18.578295Z","time spent":"519.670203ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T13:37:19.099495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.793421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T13:37:19.099542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.424317ms","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-23T13:37:19.099611Z","caller":"traceutil/trace.go:171","msg":"trace[1742715792] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3594; }","duration":"459.539213ms","start":"2026-04-23T13:37:18.640052Z","end":"2026-04-23T13:37:19.099591Z","steps":["trace[1742715792] 'agreement among raft nodes before linearized reading' (duration: 459.355954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:37:19.099663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:37:18.640034Z","time spent":"459.615596ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":547,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-23T13:37:19.09993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.288007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-04-23T13:37:19.099989Z","caller":"traceutil/trace.go:171","msg":"trace[1705659366] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:3594; }","duration":"294.383322ms","start":"2026-04-23T13:37:18.805588Z","end":"2026-04-23T13:37:19.099971Z","steps":["trace[1705659366] 'agreement among raft nodes before linearized reading' (duration: 294.245595ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:37:19.099554Z","caller":"traceutil/trace.go:171","msg":"trace[1983290611] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:3594; }","duration":"487.877675ms","start":"2026-04-23T13:37:18.611661Z","end":"2026-04-23T13:37:19.099539Z","steps":["trace[1983290611] 'agreement among raft nodes before linearized reading' (duration: 487.7767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:37:19.100085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:37:18.611654Z","time spent":"488.389071ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-23T13:37:19.100501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.909289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T13:37:19.100556Z","caller":"traceutil/trace.go:171","msg":"trace[866884185] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:3594; }","duration":"440.004914ms","start":"2026-04-23T13:37:18.660538Z","end":"2026-04-23T13:37:19.100542Z","steps":["trace[866884185] 'agreement among raft nodes before linearized reading' (duration: 439.795124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:37:19.100602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:37:18.660498Z","time spent":"440.090199ms","remote":"127.0.0.1:60574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":23,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-23T13:38:10.055079Z","caller":"traceutil/trace.go:171","msg":"trace[902166423] linearizableReadLoop","detail":"{readStateIndex:4537; appliedIndex:4536; }","duration":"100.819061ms","start":"2026-04-23T13:38:09.95423Z","end":"2026-04-23T13:38:10.055049Z","steps":["trace[902166423] 'read index received' (duration: 57.871097ms)","trace[902166423] 'applied index is now lower than readState.Index' (duration: 42.946984ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:38:10.05556Z","caller":"traceutil/trace.go:171","msg":"trace[774290109] transaction","detail":"{read_only:false; response_revision:4352; number_of_response:1; }","duration":"131.181958ms","start":"2026-04-23T13:38:09.924131Z","end":"2026-04-23T13:38:10.055313Z","steps":["trace[774290109] 'process raft request' (duration: 87.960534ms)","trace[774290109] 'compare' (duration: 42.822838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:38:10.055763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.462277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:1 size:232"} {"level":"info","ts":"2026-04-23T13:38:10.055953Z","caller":"traceutil/trace.go:171","msg":"trace[673901777] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:1; response_revision:4352; }","duration":"101.726707ms","start":"2026-04-23T13:38:09.954178Z","end":"2026-04-23T13:38:10.055904Z","steps":["trace[673901777] 'agreement among raft nodes before linearized reading' (duration: 101.388533ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:10.090035Z","caller":"traceutil/trace.go:171","msg":"trace[410217811] transaction","detail":"{read_only:false; response_revision:4353; number_of_response:1; }","duration":"125.809526ms","start":"2026-04-23T13:38:09.964201Z","end":"2026-04-23T13:38:10.090011Z","steps":["trace[410217811] 'process raft request' (duration: 125.352428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:38:10.090373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.980604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-23T13:38:10.090905Z","caller":"traceutil/trace.go:171","msg":"trace[947551955] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4354; }","duration":"134.439192ms","start":"2026-04-23T13:38:09.956331Z","end":"2026-04-23T13:38:10.09077Z","steps":["trace[947551955] 'agreement among raft nodes before linearized reading' (duration: 133.914792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:38:10.090448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.503165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-23T13:38:10.091068Z","caller":"traceutil/trace.go:171","msg":"trace[308123022] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4354; }","duration":"126.13867ms","start":"2026-04-23T13:38:09.9649Z","end":"2026-04-23T13:38:10.091039Z","steps":["trace[308123022] 'agreement among raft nodes before linearized reading' (duration: 125.443963ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:10.09042Z","caller":"traceutil/trace.go:171","msg":"trace[1193363141] transaction","detail":"{read_only:false; response_revision:4354; number_of_response:1; }","duration":"119.884529ms","start":"2026-04-23T13:38:09.970476Z","end":"2026-04-23T13:38:10.090361Z","steps":["trace[1193363141] 'process raft request' (duration: 119.233582ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:15.326924Z","caller":"traceutil/trace.go:171","msg":"trace[1864422685] linearizableReadLoop","detail":"{readStateIndex:4705; appliedIndex:4704; }","duration":"119.087635ms","start":"2026-04-23T13:38:15.207808Z","end":"2026-04-23T13:38:15.326895Z","steps":["trace[1864422685] 'read index received' (duration: 106.488762ms)","trace[1864422685] 'applied index is now lower than readState.Index' (duration: 12.597633ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:38:15.327039Z","caller":"traceutil/trace.go:171","msg":"trace[1846982742] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4515; }","duration":"121.357433ms","start":"2026-04-23T13:38:15.205638Z","end":"2026-04-23T13:38:15.326995Z","steps":["trace[1846982742] 'process raft request' (duration: 108.668587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:38:15.327389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.494951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-23T13:38:15.327512Z","caller":"traceutil/trace.go:171","msg":"trace[1935312136] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4515; }","duration":"119.682358ms","start":"2026-04-23T13:38:15.207804Z","end":"2026-04-23T13:38:15.327486Z","steps":["trace[1935312136] 'agreement among raft nodes before linearized reading' (duration: 119.21039ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:15.354841Z","caller":"traceutil/trace.go:171","msg":"trace[1886324090] transaction","detail":"{read_only:false; response_revision:4516; number_of_response:1; }","duration":"140.621636ms","start":"2026-04-23T13:38:15.214186Z","end":"2026-04-23T13:38:15.354808Z","steps":["trace[1886324090] 'process raft request' (duration: 140.253272ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:15.354857Z","caller":"traceutil/trace.go:171","msg":"trace[265239302] transaction","detail":"{read_only:false; response_revision:4517; number_of_response:1; }","duration":"140.619206ms","start":"2026-04-23T13:38:15.214212Z","end":"2026-04-23T13:38:15.354831Z","steps":["trace[265239302] 'process raft request' (duration: 140.418978ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:15.474119Z","caller":"traceutil/trace.go:171","msg":"trace[626099213] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4517; }","duration":"133.864522ms","start":"2026-04-23T13:38:15.340239Z","end":"2026-04-23T13:38:15.474103Z","steps":["trace[626099213] 'process raft request' (duration: 131.25492ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:15.47428Z","caller":"traceutil/trace.go:171","msg":"trace[244986102] linearizableReadLoop","detail":"{readStateIndex:4708; appliedIndex:4707; }","duration":"119.574203ms","start":"2026-04-23T13:38:15.35469Z","end":"2026-04-23T13:38:15.474264Z","steps":["trace[244986102] 'read index received' (duration: 116.798965ms)","trace[244986102] 'applied index is now lower than readState.Index' (duration: 2.773728ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:38:15.4745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.839547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-23T13:38:15.474541Z","caller":"traceutil/trace.go:171","msg":"trace[1936782563] transaction","detail":"{read_only:false; response_revision:4519; number_of_response:1; }","duration":"109.413647ms","start":"2026-04-23T13:38:15.365119Z","end":"2026-04-23T13:38:15.474533Z","steps":["trace[1936782563] 'process raft request' (duration: 109.333824ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:15.47454Z","caller":"traceutil/trace.go:171","msg":"trace[997742501] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4517; }","duration":"140.93188ms","start":"2026-04-23T13:38:15.333598Z","end":"2026-04-23T13:38:15.474529Z","steps":["trace[997742501] 'agreement among raft nodes before linearized reading' (duration: 140.771943ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:15.474761Z","caller":"traceutil/trace.go:171","msg":"trace[747673300] transaction","detail":"{read_only:false; response_revision:4518; number_of_response:1; }","duration":"111.105163ms","start":"2026-04-23T13:38:15.363648Z","end":"2026-04-23T13:38:15.474753Z","steps":["trace[747673300] 'process raft request' (duration: 110.416246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:38:15.475332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.021323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11454"} {"level":"info","ts":"2026-04-23T13:38:15.475368Z","caller":"traceutil/trace.go:171","msg":"trace[1653146419] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4519; }","duration":"108.087416ms","start":"2026-04-23T13:38:15.367271Z","end":"2026-04-23T13:38:15.475359Z","steps":["trace[1653146419] 'agreement among raft nodes before linearized reading' (duration: 107.242353ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:22.413944Z","caller":"traceutil/trace.go:171","msg":"trace[1246847476] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"101.934074ms","start":"2026-04-23T13:38:22.311985Z","end":"2026-04-23T13:38:22.413919Z","steps":["trace[1246847476] 'process raft request' (duration: 85.231319ms)","trace[1246847476] 'compare' (duration: 16.55876ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:38:22.940241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.792414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-23T13:38:22.94035Z","caller":"traceutil/trace.go:171","msg":"trace[1294760745] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4661; }","duration":"424.919818ms","start":"2026-04-23T13:38:22.515407Z","end":"2026-04-23T13:38:22.940327Z","steps":["trace[1294760745] 'range keys from in-memory index tree' (duration: 424.664768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:38:22.940403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:38:22.515398Z","time spent":"424.992222ms","remote":"127.0.0.1:60418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":1015,"request content":"key:\"/registry/services/specs/monitoring/prometheus-operated\" "} {"level":"warn","ts":"2026-04-23T13:38:22.940782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.70157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T13:38:22.940823Z","caller":"traceutil/trace.go:171","msg":"trace[1459874565] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4661; }","duration":"309.778043ms","start":"2026-04-23T13:38:22.631034Z","end":"2026-04-23T13:38:22.940812Z","steps":["trace[1459874565] 'count revisions from in-memory index tree' (duration: 309.619287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:38:22.940857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:38:22.631016Z","time spent":"309.831355ms","remote":"127.0.0.1:56014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":22,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-04-23T13:38:23.458821Z","caller":"traceutil/trace.go:171","msg":"trace[905497196] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"309.761626ms","start":"2026-04-23T13:38:23.149015Z","end":"2026-04-23T13:38:23.458777Z","steps":["trace[905497196] 'process raft request' (duration: 220.709385ms)","trace[905497196] 'compare' (duration: 88.726689ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:38:23.459085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:38:23.148988Z","time spent":"309.960093ms","remote":"127.0.0.1:60402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1272,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:38:23.459235Z","caller":"traceutil/trace.go:171","msg":"trace[318930046] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"310.184912ms","start":"2026-04-23T13:38:23.149022Z","end":"2026-04-23T13:38:23.459207Z","steps":["trace[318930046] 'process raft request' (duration: 309.677753ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:23.459425Z","caller":"traceutil/trace.go:171","msg":"trace[1116831865] linearizableReadLoop","detail":"{readStateIndex:4862; appliedIndex:4858; }","duration":"251.584113ms","start":"2026-04-23T13:38:23.207811Z","end":"2026-04-23T13:38:23.459395Z","steps":["trace[1116831865] 'read index received' (duration: 17.71132ms)","trace[1116831865] 'applied index is now lower than readState.Index' (duration: 233.871593ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:38:23.459452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:38:23.149012Z","time spent":"310.322217ms","remote":"127.0.0.1:60402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:38:23.459486Z","caller":"traceutil/trace.go:171","msg":"trace[928583670] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"309.087179ms","start":"2026-04-23T13:38:23.150368Z","end":"2026-04-23T13:38:23.459455Z","steps":["trace[928583670] 'process raft request' (duration: 308.740976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:38:23.459643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.822133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-23T13:38:23.4597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T13:38:23.15036Z","time spent":"309.265766ms","remote":"127.0.0.1:60504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1392,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T13:38:23.459789Z","caller":"traceutil/trace.go:171","msg":"trace[2046474081] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:4667; }","duration":"251.997869ms","start":"2026-04-23T13:38:23.207774Z","end":"2026-04-23T13:38:23.459772Z","steps":["trace[2046474081] 'agreement among raft nodes before linearized reading' (duration: 251.727859ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:23.727586Z","caller":"traceutil/trace.go:171","msg":"trace[756883905] linearizableReadLoop","detail":"{readStateIndex:4866; appliedIndex:4864; }","duration":"197.842009ms","start":"2026-04-23T13:38:23.529703Z","end":"2026-04-23T13:38:23.727545Z","steps":["trace[756883905] 'read index received' (duration: 189.990732ms)","trace[756883905] 'applied index is now lower than readState.Index' (duration: 7.850247ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T13:38:23.728068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.348468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T13:38:23.728076Z","caller":"traceutil/trace.go:171","msg":"trace[545963021] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"239.419884ms","start":"2026-04-23T13:38:23.488639Z","end":"2026-04-23T13:38:23.728059Z","steps":["trace[545963021] 'process raft request' (duration: 231.164571ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:23.728113Z","caller":"traceutil/trace.go:171","msg":"trace[387205776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4670; }","duration":"198.454232ms","start":"2026-04-23T13:38:23.529649Z","end":"2026-04-23T13:38:23.728103Z","steps":["trace[387205776] 'agreement among raft nodes before linearized reading' (duration: 198.184022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:38:23.728181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.416651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T13:38:23.728279Z","caller":"traceutil/trace.go:171","msg":"trace[823595089] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4670; }","duration":"184.563136ms","start":"2026-04-23T13:38:23.543693Z","end":"2026-04-23T13:38:23.728257Z","steps":["trace[823595089] 'agreement among raft nodes before linearized reading' (duration: 184.233184ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:23.728268Z","caller":"traceutil/trace.go:171","msg":"trace[388624828] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"215.357951ms","start":"2026-04-23T13:38:23.512887Z","end":"2026-04-23T13:38:23.728245Z","steps":["trace[388624828] 'process raft request' (duration: 214.52066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:38:23.728319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.928438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T13:38:23.728382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.157263ms","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-23T13:38:23.728403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.643506ms","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-23T13:38:23.728439Z","caller":"traceutil/trace.go:171","msg":"trace[1193991731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4670; }","duration":"183.244287ms","start":"2026-04-23T13:38:23.545176Z","end":"2026-04-23T13:38:23.72842Z","steps":["trace[1193991731] 'agreement among raft nodes before linearized reading' (duration: 183.121772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:38:23.728201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.808651ms","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-23T13:38:23.72849Z","caller":"traceutil/trace.go:171","msg":"trace[921928006] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4670; }","duration":"181.787591ms","start":"2026-04-23T13:38:23.546685Z","end":"2026-04-23T13:38:23.728472Z","steps":["trace[921928006] 'agreement among raft nodes before linearized reading' (duration: 181.4853ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:23.728537Z","caller":"traceutil/trace.go:171","msg":"trace[171581722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4670; }","duration":"173.173365ms","start":"2026-04-23T13:38:23.555348Z","end":"2026-04-23T13:38:23.728521Z","steps":["trace[171581722] 'agreement among raft nodes before linearized reading' (duration: 172.744849ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:23.728402Z","caller":"traceutil/trace.go:171","msg":"trace[1083689427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4670; }","duration":"172.072744ms","start":"2026-04-23T13:38:23.556308Z","end":"2026-04-23T13:38:23.728381Z","steps":["trace[1083689427] 'agreement among raft nodes before linearized reading' (duration: 171.829524ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:36.132949Z","caller":"traceutil/trace.go:171","msg":"trace[2081590681] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"232.055548ms","start":"2026-04-23T13:38:35.900687Z","end":"2026-04-23T13:38:36.132742Z","steps":["trace[2081590681] 'process raft request' (duration: 190.829863ms)","trace[2081590681] 'compare' (duration: 40.856922ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:38:36.155563Z","caller":"traceutil/trace.go:171","msg":"trace[585030559] linearizableReadLoop","detail":"{readStateIndex:5066; appliedIndex:5060; }","duration":"164.164602ms","start":"2026-04-23T13:38:35.991372Z","end":"2026-04-23T13:38:36.155537Z","steps":["trace[585030559] 'read index received' (duration: 100.154548ms)","trace[585030559] 'applied index is now lower than readState.Index' (duration: 64.009284ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:38:36.155693Z","caller":"traceutil/trace.go:171","msg":"trace[118507624] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"254.308849ms","start":"2026-04-23T13:38:35.901372Z","end":"2026-04-23T13:38:36.155681Z","steps":["trace[118507624] 'process raft request' (duration: 254.075611ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:36.155656Z","caller":"traceutil/trace.go:171","msg":"trace[860244753] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"254.377451ms","start":"2026-04-23T13:38:35.901222Z","end":"2026-04-23T13:38:36.155599Z","steps":["trace[860244753] 'process raft request' (duration: 254.199575ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:36.155801Z","caller":"traceutil/trace.go:171","msg":"trace[1690072151] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"255.025724ms","start":"2026-04-23T13:38:35.900769Z","end":"2026-04-23T13:38:36.155794Z","steps":["trace[1690072151] 'process raft request' (duration: 254.546777ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:36.155866Z","caller":"traceutil/trace.go:171","msg":"trace[16221088] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"254.444804ms","start":"2026-04-23T13:38:35.901415Z","end":"2026-04-23T13:38:36.15586Z","steps":["trace[16221088] 'process raft request' (duration: 254.05993ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:38:36.155904Z","caller":"traceutil/trace.go:171","msg":"trace[489405676] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"254.252147ms","start":"2026-04-23T13:38:35.90164Z","end":"2026-04-23T13:38:36.155892Z","steps":["trace[489405676] 'process raft request' (duration: 253.863683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:38:36.155933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.541185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-23T13:38:36.156003Z","caller":"traceutil/trace.go:171","msg":"trace[1408093009] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4863; }","duration":"164.66886ms","start":"2026-04-23T13:38:35.991311Z","end":"2026-04-23T13:38:36.15598Z","steps":["trace[1408093009] 'agreement among raft nodes before linearized reading' (duration: 164.423541ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:39:12.468308Z","caller":"traceutil/trace.go:171","msg":"trace[1374544197] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"100.382408ms","start":"2026-04-23T13:39:12.367904Z","end":"2026-04-23T13:39:12.468287Z","steps":["trace[1374544197] 'process raft request' (duration: 85.776624ms)","trace[1374544197] 'compare' (duration: 14.305134ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:41:30.34378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3240} {"level":"info","ts":"2026-04-23T13:41:30.442053Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3240,"took":"96.526034ms","hash":2281747974,"current-db-size-bytes":58187776,"current-db-size":"58 MB","current-db-size-in-use-bytes":31244288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-23T13:41:30.442118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2281747974,"revision":3240,"compact-revision":-1} {"level":"info","ts":"2026-04-23T13:42:37.583653Z","caller":"traceutil/trace.go:171","msg":"trace[1818154291] linearizableReadLoop","detail":"{readStateIndex:7254; appliedIndex:7253; }","duration":"200.895088ms","start":"2026-04-23T13:42:37.382742Z","end":"2026-04-23T13:42:37.583637Z","steps":["trace[1818154291] 'read index received' (duration: 74.219093ms)","trace[1818154291] 'applied index is now lower than readState.Index' (duration: 126.675345ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T13:42:37.583736Z","caller":"traceutil/trace.go:171","msg":"trace[909892063] transaction","detail":"{read_only:false; response_revision:6943; number_of_response:1; }","duration":"224.051732ms","start":"2026-04-23T13:42:37.359677Z","end":"2026-04-23T13:42:37.583729Z","steps":["trace[909892063] 'process raft request' (duration: 223.856278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:42:37.583937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.891587ms","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-23T13:42:37.584013Z","caller":"traceutil/trace.go:171","msg":"trace[1759995255] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:6943; }","duration":"163.020479ms","start":"2026-04-23T13:42:37.420974Z","end":"2026-04-23T13:42:37.583994Z","steps":["trace[1759995255] 'agreement among raft nodes before linearized reading' (duration: 162.811225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T13:42:37.584143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.397609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107303"} {"level":"warn","ts":"2026-04-23T13:42:37.584168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.391125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-04-23T13:42:37.584183Z","caller":"traceutil/trace.go:171","msg":"trace[496685532] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6943; }","duration":"201.44474ms","start":"2026-04-23T13:42:37.382726Z","end":"2026-04-23T13:42:37.584171Z","steps":["trace[496685532] 'agreement among raft nodes before linearized reading' (duration: 201.359748ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T13:42:37.584198Z","caller":"traceutil/trace.go:171","msg":"trace[553774698] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6943; }","duration":"124.442976ms","start":"2026-04-23T13:42:37.459743Z","end":"2026-04-23T13:42:37.584186Z","steps":["trace[553774698] 'agreement among raft nodes before linearized reading' (duration: 124.368324ms)"],"step_count":1}