{"level":"warn","ts":"2026-04-22T06:01:34.701621Z","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-22T06:01:34.701697Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-22T06:01:34.701753Z","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-22T06:01:34.701764Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-04-22T06:01:34.701778Z","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-22T06:01:34.702367Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-04-22T06:01:34.702437Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-22T06:01:34.706963Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.351374ms"} {"level":"info","ts":"2026-04-22T06:01:34.712842Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-04-22T06:01:34.712932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T06:01:34.712959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-04-22T06:01:34.712977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T06:01:34.712988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-04-22T06:01:34.713044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-04-22T06:01:34.738305Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T06:01:34.739756Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T06:01:34.742193Z","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-22T06:01:34.743632Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T06:01:34.743731Z","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-22T06:01:34.743799Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T06:01:34.743821Z","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-22T06:01:34.743842Z","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-22T06:01:34.7456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-04-22T06:01:34.745735Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-04-22T06:01:34.751792Z","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-22T06:01:34.751895Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-04-22T06:01:34.751937Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-04-22T06:01:34.751992Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T06:01:34.752022Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T06:01:35.513598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T06:01:35.513652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T06:01:35.513677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-04-22T06:01:35.513691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-04-22T06:01:35.513698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-04-22T06:01:35.51371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-04-22T06:01:35.513719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-04-22T06:01:35.515117Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T06:01:35.515129Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:01:35.515182Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:01:35.515184Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:01:35.51529Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T06:01:35.515309Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T06:01:35.516331Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:01:35.516441Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:01:35.516478Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:01:35.519011Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-04-22T06:01:35.520053Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T06:02:00.082993Z","caller":"traceutil/trace.go:171","msg":"trace[1629079711] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"430.991647ms","start":"2026-04-22T06:01:59.651976Z","end":"2026-04-22T06:02:00.082968Z","steps":["trace[1629079711] 'read index received' (duration: 430.833786ms)","trace[1629079711] 'applied index is now lower than readState.Index' (duration: 156.971µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:02:00.083138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.141758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:02:00.083187Z","caller":"traceutil/trace.go:171","msg":"trace[803362589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:469; }","duration":"431.219138ms","start":"2026-04-22T06:01:59.65196Z","end":"2026-04-22T06:02:00.083179Z","steps":["trace[803362589] 'agreement among raft nodes before linearized reading' (duration: 431.121937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:00.083213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:01:59.651888Z","time spent":"431.320239ms","remote":"127.0.0.1:37488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T06:02:00.083338Z","caller":"traceutil/trace.go:171","msg":"trace[632175387] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"461.713506ms","start":"2026-04-22T06:01:59.621585Z","end":"2026-04-22T06:02:00.083299Z","steps":["trace[632175387] 'process raft request' (duration: 461.240253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:00.084076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:01:59.62152Z","time spent":"461.929028ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:02:04.185948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.707589ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-22T06:02:04.189227Z","caller":"traceutil/trace.go:171","msg":"trace[1763061295] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"326.988304ms","start":"2026-04-22T06:02:03.862209Z","end":"2026-04-22T06:02:04.189197Z","steps":["trace[1763061295] 'process raft request' (duration: 326.749812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:04.189365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:03.862184Z","time spent":"327.111645ms","remote":"127.0.0.1:37538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:02:04.189564Z","caller":"traceutil/trace.go:171","msg":"trace[587285119] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"311.93698ms","start":"2026-04-22T06:02:03.877617Z","end":"2026-04-22T06:02:04.189554Z","steps":["trace[587285119] 'process raft request' (duration: 311.654428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:04.189618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:03.877586Z","time spent":"312.01053ms","remote":"127.0.0.1:37538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:02:04.189797Z","caller":"traceutil/trace.go:171","msg":"trace[1081052456] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"312.567433ms","start":"2026-04-22T06:02:03.877207Z","end":"2026-04-22T06:02:04.189775Z","steps":["trace[1081052456] 'process raft request' (duration: 312.02411ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:04.189776Z","caller":"traceutil/trace.go:171","msg":"trace[1701616775] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"307.122661ms","start":"2026-04-22T06:02:03.882597Z","end":"2026-04-22T06:02:04.18972Z","steps":["trace[1701616775] 'process raft request' (duration: 306.83331ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:04.189814Z","caller":"traceutil/trace.go:171","msg":"trace[744355498] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"311.955129ms","start":"2026-04-22T06:02:03.877824Z","end":"2026-04-22T06:02:04.189779Z","steps":["trace[744355498] 'process raft request' (duration: 311.480956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:04.189951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:03.877202Z","time spent":"312.664483ms","remote":"127.0.0.1:37538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:02:04.189896Z","caller":"traceutil/trace.go:171","msg":"trace[118160692] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"323.545734ms","start":"2026-04-22T06:02:03.866275Z","end":"2026-04-22T06:02:04.189821Z","steps":["trace[118160692] 'process raft request' (duration: 322.8494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:04.189994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:03.882589Z","time spent":"307.302392ms","remote":"127.0.0.1:37538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:02:04.190002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:03.877816Z","time spent":"312.08877ms","remote":"127.0.0.1:37538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:02:04.190106Z","caller":"traceutil/trace.go:171","msg":"trace[1606413829] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"313.183057ms","start":"2026-04-22T06:02:03.876907Z","end":"2026-04-22T06:02:04.19009Z","steps":["trace[1606413829] 'process raft request' (duration: 312.273031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:04.190132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:03.866249Z","time spent":"323.774046ms","remote":"127.0.0.1:37538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:02:04.190165Z","caller":"traceutil/trace.go:171","msg":"trace[678677753] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"297.556168ms","start":"2026-04-22T06:02:03.892597Z","end":"2026-04-22T06:02:04.190153Z","steps":["trace[678677753] 'process raft request' (duration: 296.876524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:04.190165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:03.8769Z","time spent":"313.239187ms","remote":"127.0.0.1:37538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:02:04.189739Z","caller":"traceutil/trace.go:171","msg":"trace[1216555038] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"322.372077ms","start":"2026-04-22T06:02:03.867349Z","end":"2026-04-22T06:02:04.189721Z","steps":["trace[1216555038] 'process raft request' (duration: 321.807164ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:04.190323Z","caller":"traceutil/trace.go:171","msg":"trace[113182657] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"277.567316ms","start":"2026-04-22T06:02:03.912747Z","end":"2026-04-22T06:02:04.190314Z","steps":["trace[113182657] 'process raft request' (duration: 276.766521ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:04.189691Z","caller":"traceutil/trace.go:171","msg":"trace[184392965] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"310.463951ms","start":"2026-04-22T06:02:03.879198Z","end":"2026-04-22T06:02:04.189662Z","steps":["trace[184392965] 'process raft request' (duration: 310.137579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:04.190522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:03.879191Z","time spent":"311.285086ms","remote":"127.0.0.1:37538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:02:04.190729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:03.867343Z","time spent":"322.935631ms","remote":"127.0.0.1:37538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:02:04.191401Z","caller":"traceutil/trace.go:171","msg":"trace[1318339197] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"156.620986ms","start":"2026-04-22T06:02:04.034765Z","end":"2026-04-22T06:02:04.191386Z","steps":["trace[1318339197] 'process raft request' (duration: 155.895182ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:05.762056Z","caller":"traceutil/trace.go:171","msg":"trace[2092003671] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"254.072628ms","start":"2026-04-22T06:02:05.507951Z","end":"2026-04-22T06:02:05.762023Z","steps":["trace[2092003671] 'process raft request' (duration: 253.761937ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:05.769991Z","caller":"traceutil/trace.go:171","msg":"trace[1926896093] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:606; }","duration":"225.738691ms","start":"2026-04-22T06:02:05.544215Z","end":"2026-04-22T06:02:05.769954Z","steps":["trace[1926896093] 'read index received' (duration: 217.534956ms)","trace[1926896093] 'applied index is now lower than readState.Index' (duration: 8.202675ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:02:05.770269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.023043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-04-22T06:02:05.770313Z","caller":"traceutil/trace.go:171","msg":"trace[1468035239] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:589; }","duration":"226.136063ms","start":"2026-04-22T06:02:05.544164Z","end":"2026-04-22T06:02:05.7703Z","steps":["trace[1468035239] 'agreement among raft nodes before linearized reading' (duration: 225.920222ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:05.770521Z","caller":"traceutil/trace.go:171","msg":"trace[358196306] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"238.479131ms","start":"2026-04-22T06:02:05.532032Z","end":"2026-04-22T06:02:05.770511Z","steps":["trace[358196306] 'process raft request' (duration: 237.757927ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:07.459474Z","caller":"traceutil/trace.go:171","msg":"trace[1791475434] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"357.692306ms","start":"2026-04-22T06:02:07.101743Z","end":"2026-04-22T06:02:07.459435Z","steps":["trace[1791475434] 'read index received' (duration: 357.520535ms)","trace[1791475434] 'applied index is now lower than readState.Index' (duration: 170.201µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:02:07.460088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.270249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-22T06:02:07.460246Z","caller":"traceutil/trace.go:171","msg":"trace[544323345] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"366.859935ms","start":"2026-04-22T06:02:07.092718Z","end":"2026-04-22T06:02:07.459578Z","steps":["trace[544323345] 'process raft request' (duration: 366.495113ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:07.460207Z","caller":"traceutil/trace.go:171","msg":"trace[1297651160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"358.4491ms","start":"2026-04-22T06:02:07.10174Z","end":"2026-04-22T06:02:07.460189Z","steps":["trace[1297651160] 'agreement among raft nodes before linearized reading' (duration: 357.892777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:07.460368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:07.101708Z","time spent":"358.645191ms","remote":"127.0.0.1:37764","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-22T06:02:07.460449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:07.092699Z","time spent":"367.65253ms","remote":"127.0.0.1:37590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:02:07.790313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.627067ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:02:07.790504Z","caller":"traceutil/trace.go:171","msg":"trace[355194257] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:592; }","duration":"308.844158ms","start":"2026-04-22T06:02:07.481628Z","end":"2026-04-22T06:02:07.790472Z","steps":["trace[355194257] 'range keys from in-memory index tree' (duration: 308.594007ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:07.79404Z","caller":"traceutil/trace.go:171","msg":"trace[1922554247] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"334.308787ms","start":"2026-04-22T06:02:07.459694Z","end":"2026-04-22T06:02:07.794003Z","steps":["trace[1922554247] 'read index received' (duration: 334.206427ms)","trace[1922554247] 'applied index is now lower than readState.Index' (duration: 101.1µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:02:07.794461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.61737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T06:02:07.794559Z","caller":"traceutil/trace.go:171","msg":"trace[1935286348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"689.74842ms","start":"2026-04-22T06:02:07.104788Z","end":"2026-04-22T06:02:07.794537Z","steps":["trace[1935286348] 'agreement among raft nodes before linearized reading' (duration: 689.390948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:07.794612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:07.104778Z","time spent":"689.825401ms","remote":"127.0.0.1:37764","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-22T06:02:07.794805Z","caller":"traceutil/trace.go:171","msg":"trace[807198560] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"329.468651ms","start":"2026-04-22T06:02:07.465318Z","end":"2026-04-22T06:02:07.794787Z","steps":["trace[807198560] 'process raft request' (duration: 329.41303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:07.795046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:07.465309Z","time spent":"329.551571ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:02:07.795205Z","caller":"traceutil/trace.go:171","msg":"trace[51769032] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"330.876809ms","start":"2026-04-22T06:02:07.464316Z","end":"2026-04-22T06:02:07.795193Z","steps":["trace[51769032] 'process raft request' (duration: 330.249625ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:07.797826Z","caller":"traceutil/trace.go:171","msg":"trace[227679741] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"530.858392ms","start":"2026-04-22T06:02:07.263231Z","end":"2026-04-22T06:02:07.79409Z","steps":["trace[227679741] 'process raft request' (duration: 530.60768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:07.797961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:07.263202Z","time spent":"534.674932ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:02:07.801073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:07.464292Z","time spent":"330.938629ms","remote":"127.0.0.1:37584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:02:07.794979Z","caller":"traceutil/trace.go:171","msg":"trace[1824119117] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"330.698357ms","start":"2026-04-22T06:02:07.46423Z","end":"2026-04-22T06:02:07.794928Z","steps":["trace[1824119117] 'process raft request' (duration: 330.270155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:07.80129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:07.464204Z","time spent":"337.007152ms","remote":"127.0.0.1:37690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:02:07.801512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.308233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-22T06:02:07.801546Z","caller":"traceutil/trace.go:171","msg":"trace[2106165213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"423.342523ms","start":"2026-04-22T06:02:07.378194Z","end":"2026-04-22T06:02:07.801536Z","steps":["trace[2106165213] 'agreement among raft nodes before linearized reading' (duration: 415.930963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:07.801616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:07.37813Z","time spent":"423.435775ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T06:02:07.802206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.869084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:02:07.802281Z","caller":"traceutil/trace.go:171","msg":"trace[587613182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"149.010084ms","start":"2026-04-22T06:02:07.653254Z","end":"2026-04-22T06:02:07.802264Z","steps":["trace[587613182] 'agreement among raft nodes before linearized reading' (duration: 148.843793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:11.523524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.474198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qrh7n\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-22T06:02:11.523636Z","caller":"traceutil/trace.go:171","msg":"trace[593977107] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qrh7n; range_end:; response_count:1; response_revision:621; }","duration":"142.650499ms","start":"2026-04-22T06:02:11.380961Z","end":"2026-04-22T06:02:11.523612Z","steps":["trace[593977107] 'agreement among raft nodes before linearized reading' (duration: 142.357227ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:11.524173Z","caller":"traceutil/trace.go:171","msg":"trace[936902354] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"389.648092ms","start":"2026-04-22T06:02:11.133634Z","end":"2026-04-22T06:02:11.523282Z","steps":["trace[936902354] 'process raft request' (duration: 389.19226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:11.524306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:11.133606Z","time spent":"390.620057ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:02:11.523875Z","caller":"traceutil/trace.go:171","msg":"trace[697493095] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"142.127625ms","start":"2026-04-22T06:02:11.381029Z","end":"2026-04-22T06:02:11.523157Z","steps":["trace[697493095] 'read index received' (duration: 141.772054ms)","trace[697493095] 'applied index is now lower than readState.Index' (duration: 354.561µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:02:11.554152Z","caller":"traceutil/trace.go:171","msg":"trace[1999102449] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"154.481381ms","start":"2026-04-22T06:02:11.399656Z","end":"2026-04-22T06:02:11.554137Z","steps":["trace[1999102449] 'process raft request' (duration: 153.898088ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:02:11.931111Z","caller":"traceutil/trace.go:171","msg":"trace[1071389608] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"391.895784ms","start":"2026-04-22T06:02:11.539184Z","end":"2026-04-22T06:02:11.93108Z","steps":["trace[1071389608] 'process raft request' (duration: 391.676682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:11.93131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:11.539155Z","time spent":"392.052804ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:02:12.942766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.508105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:02:12.943275Z","caller":"traceutil/trace.go:171","msg":"trace[75173755] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"1.288936861s","start":"2026-04-22T06:02:11.654318Z","end":"2026-04-22T06:02:12.943254Z","steps":["trace[75173755] 'read index received' (duration: 276.622061ms)","trace[75173755] 'applied index is now lower than readState.Index' (duration: 1.01231264s)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:02:12.943312Z","caller":"traceutil/trace.go:171","msg":"trace[1755449439] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.324330749s","start":"2026-04-22T06:02:11.618934Z","end":"2026-04-22T06:02:12.943264Z","steps":["trace[1755449439] 'process raft request' (duration: 478.2511ms)","trace[1755449439] 'compare' (duration: 845.130384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:02:12.943496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:11.618904Z","time spent":"1.324500729s","remote":"127.0.0.1:37736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:02:12.943505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289180292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:02:12.943616Z","caller":"traceutil/trace.go:171","msg":"trace[1068411285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"1.289330832s","start":"2026-04-22T06:02:11.654273Z","end":"2026-04-22T06:02:12.943604Z","steps":["trace[1068411285] 'agreement among raft nodes before linearized reading' (duration: 1.289087551s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:12.943654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114637974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-22T06:02:12.943675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:11.654257Z","time spent":"1.289396913s","remote":"127.0.0.1:37482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T06:02:12.943695Z","caller":"traceutil/trace.go:171","msg":"trace[1794450490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"1.114707654s","start":"2026-04-22T06:02:11.828976Z","end":"2026-04-22T06:02:12.943684Z","steps":["trace[1794450490] 'agreement among raft nodes before linearized reading' (duration: 1.114573483s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:12.943876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.628798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-22T06:02:12.943732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:11.828965Z","time spent":"1.114759004s","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T06:02:12.94408Z","caller":"traceutil/trace.go:171","msg":"trace[297850559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"709.723538ms","start":"2026-04-22T06:02:12.234192Z","end":"2026-04-22T06:02:12.943915Z","steps":["trace[297850559] 'agreement among raft nodes before linearized reading' (duration: 709.590427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:12.944122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:12.234167Z","time spent":"709.946129ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T06:02:12.944131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118180983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T06:02:12.944311Z","caller":"traceutil/trace.go:171","msg":"trace[1893139340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"1.118454864s","start":"2026-04-22T06:02:11.825824Z","end":"2026-04-22T06:02:12.944279Z","steps":["trace[1893139340] 'agreement among raft nodes before linearized reading' (duration: 1.117956491s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:12.944366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128018355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-22T06:02:12.944443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:11.825782Z","time spent":"1.118647615s","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T06:02:12.944495Z","caller":"traceutil/trace.go:171","msg":"trace[1840073804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"1.128246736s","start":"2026-04-22T06:02:11.816218Z","end":"2026-04-22T06:02:12.944465Z","steps":["trace[1840073804] 'agreement among raft nodes before linearized reading' (duration: 1.127326061s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:12.944559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:11.816189Z","time spent":"1.128359386s","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T06:02:28.652514Z","caller":"traceutil/trace.go:171","msg":"trace[435676801] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"359.627398ms","start":"2026-04-22T06:02:28.292862Z","end":"2026-04-22T06:02:28.652489Z","steps":["trace[435676801] 'read index received' (duration: 359.481667ms)","trace[435676801] 'applied index is now lower than readState.Index' (duration: 144.691µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:02:28.65263Z","caller":"traceutil/trace.go:171","msg":"trace[1190178858] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"459.860346ms","start":"2026-04-22T06:02:28.192741Z","end":"2026-04-22T06:02:28.652602Z","steps":["trace[1190178858] 'process raft request' (duration: 459.540204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:28.652774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.917109ms","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-22T06:02:28.652794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:28.192704Z","time spent":"460.004186ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:02:28.652813Z","caller":"traceutil/trace.go:171","msg":"trace[1469444746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"360.018351ms","start":"2026-04-22T06:02:28.292786Z","end":"2026-04-22T06:02:28.652804Z","steps":["trace[1469444746] 'agreement among raft nodes before linearized reading' (duration: 359.89517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:28.652846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:02:28.292745Z","time spent":"360.094161ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T06:02:28.743562Z","caller":"traceutil/trace.go:171","msg":"trace[672051255] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"247.465823ms","start":"2026-04-22T06:02:28.496074Z","end":"2026-04-22T06:02:28.743539Z","steps":["trace[672051255] 'process raft request' (duration: 247.320042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:02:28.743663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.518992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-22T06:02:28.74372Z","caller":"traceutil/trace.go:171","msg":"trace[1464082450] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:785; }","duration":"146.622553ms","start":"2026-04-22T06:02:28.597085Z","end":"2026-04-22T06:02:28.743708Z","steps":["trace[1464082450] 'agreement among raft nodes before linearized reading' (duration: 146.453002ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:02.204629Z","caller":"traceutil/trace.go:171","msg":"trace[1451094027] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"218.458133ms","start":"2026-04-22T06:03:01.986144Z","end":"2026-04-22T06:03:02.204602Z","steps":["trace[1451094027] 'read index received' (duration: 218.316507ms)","trace[1451094027] 'applied index is now lower than readState.Index' (duration: 139.986µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:03:02.204857Z","caller":"traceutil/trace.go:171","msg":"trace[15918075] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"493.294862ms","start":"2026-04-22T06:03:01.711545Z","end":"2026-04-22T06:03:02.20484Z","steps":["trace[15918075] 'process raft request' (duration: 492.84801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:02.204979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.810741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-22T06:03:02.205019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:03:01.711522Z","time spent":"493.391268ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:03:02.205094Z","caller":"traceutil/trace.go:171","msg":"trace[1158841991] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1205; }","duration":"218.993949ms","start":"2026-04-22T06:03:01.986071Z","end":"2026-04-22T06:03:02.205065Z","steps":["trace[1158841991] 'agreement among raft nodes before linearized reading' (duration: 218.8094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:02.206454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.313143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-04-22T06:03:02.206442Z","caller":"traceutil/trace.go:171","msg":"trace[2053223250] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"107.145455ms","start":"2026-04-22T06:03:02.099229Z","end":"2026-04-22T06:03:02.206375Z","steps":["trace[2053223250] 'process raft request' (duration: 106.920424ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:02.206561Z","caller":"traceutil/trace.go:171","msg":"trace[715516409] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1206; }","duration":"194.483131ms","start":"2026-04-22T06:03:02.012054Z","end":"2026-04-22T06:03:02.206537Z","steps":["trace[715516409] 'agreement among raft nodes before linearized reading' (duration: 194.272931ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:16.446429Z","caller":"traceutil/trace.go:171","msg":"trace[861267077] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"180.199229ms","start":"2026-04-22T06:03:16.26619Z","end":"2026-04-22T06:03:16.446389Z","steps":["trace[861267077] 'process raft request' (duration: 164.012337ms)","trace[861267077] 'compare' (duration: 16.055006ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:03:24.577432Z","caller":"traceutil/trace.go:171","msg":"trace[1561549770] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"100.939563ms","start":"2026-04-22T06:03:24.476453Z","end":"2026-04-22T06:03:24.577393Z","steps":["trace[1561549770] 'process raft request' (duration: 100.721094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:46.920502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.091214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:03:46.920615Z","caller":"traceutil/trace.go:171","msg":"trace[1642848085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1851; }","duration":"267.304971ms","start":"2026-04-22T06:03:46.653287Z","end":"2026-04-22T06:03:46.920592Z","steps":["trace[1642848085] 'range keys from in-memory index tree' (duration: 266.971091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:46.920616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.990081ms","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-22T06:03:46.920673Z","caller":"traceutil/trace.go:171","msg":"trace[1410080432] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1851; }","duration":"339.083364ms","start":"2026-04-22T06:03:46.581575Z","end":"2026-04-22T06:03:46.920659Z","steps":["trace[1410080432] 'range keys from in-memory index tree' (duration: 338.801016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:46.920712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:03:46.581555Z","time spent":"339.147485ms","remote":"127.0.0.1:37584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-22T06:03:46.926038Z","caller":"traceutil/trace.go:171","msg":"trace[1458528299] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1900; }","duration":"130.615314ms","start":"2026-04-22T06:03:46.795412Z","end":"2026-04-22T06:03:46.926028Z","steps":["trace[1458528299] 'read index received' (duration: 130.547262ms)","trace[1458528299] 'applied index is now lower than readState.Index' (duration: 67.102µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:03:46.926268Z","caller":"traceutil/trace.go:171","msg":"trace[2044849203] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"252.773202ms","start":"2026-04-22T06:03:46.673431Z","end":"2026-04-22T06:03:46.926204Z","steps":["trace[2044849203] 'process raft request' (duration: 252.493124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:46.926294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.833431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:03:46.926436Z","caller":"traceutil/trace.go:171","msg":"trace[1550830283] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1852; }","duration":"131.015536ms","start":"2026-04-22T06:03:46.795393Z","end":"2026-04-22T06:03:46.926408Z","steps":["trace[1550830283] 'agreement among raft nodes before linearized reading' (duration: 130.741318ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:47.171098Z","caller":"traceutil/trace.go:171","msg":"trace[351810106] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"156.153261ms","start":"2026-04-22T06:03:47.014923Z","end":"2026-04-22T06:03:47.171077Z","steps":["trace[351810106] 'read index received' (duration: 156.023847ms)","trace[351810106] 'applied index is now lower than readState.Index' (duration: 128.024µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:03:47.171273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.232614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9b767e25-1705-42c4-a989-c43c41e3ea5c\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-22T06:03:47.171316Z","caller":"traceutil/trace.go:171","msg":"trace[625198015] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9b767e25-1705-42c4-a989-c43c41e3ea5c; range_end:; response_count:1; response_revision:1854; }","duration":"136.321017ms","start":"2026-04-22T06:03:47.034983Z","end":"2026-04-22T06:03:47.171304Z","steps":["trace[625198015] 'agreement among raft nodes before linearized reading' (duration: 136.232144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:47.173251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.319536ms","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-22T06:03:47.1733Z","caller":"traceutil/trace.go:171","msg":"trace[1885776964] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1854; }","duration":"158.437769ms","start":"2026-04-22T06:03:47.014848Z","end":"2026-04-22T06:03:47.173286Z","steps":["trace[1885776964] 'agreement among raft nodes before linearized reading' (duration: 157.184242ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:47.175685Z","caller":"traceutil/trace.go:171","msg":"trace[1967458786] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"229.567861ms","start":"2026-04-22T06:03:46.941632Z","end":"2026-04-22T06:03:47.1712Z","steps":["trace[1967458786] 'process raft request' (duration: 229.252121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:47.38497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.496902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:03:47.385941Z","caller":"traceutil/trace.go:171","msg":"trace[1906173493] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"205.060178ms","start":"2026-04-22T06:03:47.180858Z","end":"2026-04-22T06:03:47.385918Z","steps":["trace[1906173493] 'process raft request' (duration: 58.503624ms)","trace[1906173493] 'compare' (duration: 144.248495ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:03:47.391348Z","caller":"traceutil/trace.go:171","msg":"trace[1961698294] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"207.234263ms","start":"2026-04-22T06:03:47.184085Z","end":"2026-04-22T06:03:47.391319Z","steps":["trace[1961698294] 'process raft request' (duration: 207.095999ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:49.080049Z","caller":"traceutil/trace.go:171","msg":"trace[1104418135] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"148.150267ms","start":"2026-04-22T06:03:48.931873Z","end":"2026-04-22T06:03:49.080023Z","steps":["trace[1104418135] 'process raft request' (duration: 147.988943ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:52.818942Z","caller":"traceutil/trace.go:171","msg":"trace[1865894609] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1977; }","duration":"167.907301ms","start":"2026-04-22T06:03:52.651007Z","end":"2026-04-22T06:03:52.818914Z","steps":["trace[1865894609] 'read index received' (duration: 167.595672ms)","trace[1865894609] 'applied index is now lower than readState.Index' (duration: 310.479µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:03:52.81905Z","caller":"traceutil/trace.go:171","msg":"trace[349862095] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"245.663084ms","start":"2026-04-22T06:03:52.573356Z","end":"2026-04-22T06:03:52.819019Z","steps":["trace[349862095] 'process raft request' (duration: 245.310994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:52.819133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.091966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:03:52.819176Z","caller":"traceutil/trace.go:171","msg":"trace[392143501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1927; }","duration":"168.21414ms","start":"2026-04-22T06:03:52.650952Z","end":"2026-04-22T06:03:52.819166Z","steps":["trace[392143501] 'agreement among raft nodes before linearized reading' (duration: 168.112677ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:53.090938Z","caller":"traceutil/trace.go:171","msg":"trace[606460834] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"115.639923ms","start":"2026-04-22T06:03:52.975245Z","end":"2026-04-22T06:03:53.090885Z","steps":["trace[606460834] 'process raft request' (duration: 115.306804ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:03:56.505616Z","caller":"traceutil/trace.go:171","msg":"trace[1274889950] linearizableReadLoop","detail":"{readStateIndex:2011; appliedIndex:2010; }","duration":"267.944905ms","start":"2026-04-22T06:03:56.237648Z","end":"2026-04-22T06:03:56.505593Z","steps":["trace[1274889950] 'read index received' (duration: 267.775141ms)","trace[1274889950] 'applied index is now lower than readState.Index' (duration: 168.864µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:03:56.505828Z","caller":"traceutil/trace.go:171","msg":"trace[40339065] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"332.891745ms","start":"2026-04-22T06:03:56.172923Z","end":"2026-04-22T06:03:56.505815Z","steps":["trace[40339065] 'process raft request' (duration: 332.521724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:56.505966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.054087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:03:56.50603Z","caller":"traceutil/trace.go:171","msg":"trace[1714849305] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1958; }","duration":"162.17575ms","start":"2026-04-22T06:03:56.343839Z","end":"2026-04-22T06:03:56.506014Z","steps":["trace[1714849305] 'agreement among raft nodes before linearized reading' (duration: 162.075607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:56.506019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:03:56.17291Z","time spent":"332.948597ms","remote":"127.0.0.1:37584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:03:56.506384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.727746ms","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-22T06:03:56.506483Z","caller":"traceutil/trace.go:171","msg":"trace[2135776742] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1958; }","duration":"268.876641ms","start":"2026-04-22T06:03:56.237578Z","end":"2026-04-22T06:03:56.506455Z","steps":["trace[2135776742] 'agreement among raft nodes before linearized reading' (duration: 268.46153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:03:56.506555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.151698ms","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-22T06:03:56.506679Z","caller":"traceutil/trace.go:171","msg":"trace[500227926] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1958; }","duration":"101.414916ms","start":"2026-04-22T06:03:56.405238Z","end":"2026-04-22T06:03:56.506653Z","steps":["trace[500227926] 'agreement among raft nodes before linearized reading' (duration: 100.934272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:03.713607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.474449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:04:03.713793Z","caller":"traceutil/trace.go:171","msg":"trace[1046873682] linearizableReadLoop","detail":"{readStateIndex:2056; appliedIndex:2055; }","duration":"176.09332ms","start":"2026-04-22T06:04:03.537678Z","end":"2026-04-22T06:04:03.713772Z","steps":["trace[1046873682] 'read index received' (duration: 72.302583ms)","trace[1046873682] 'applied index is now lower than readState.Index' (duration: 103.789227ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:04:03.713981Z","caller":"traceutil/trace.go:171","msg":"trace[1640305014] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"236.259449ms","start":"2026-04-22T06:04:03.477705Z","end":"2026-04-22T06:04:03.713965Z","steps":["trace[1640305014] 'process raft request' (duration: 132.333528ms)","trace[1640305014] 'compare' (duration: 103.367717ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:04:03.714227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.546438ms","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-22T06:04:03.714341Z","caller":"traceutil/trace.go:171","msg":"trace[1250132592] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2001; }","duration":"160.668801ms","start":"2026-04-22T06:04:03.553642Z","end":"2026-04-22T06:04:03.714311Z","steps":["trace[1250132592] 'agreement among raft nodes before linearized reading' (duration: 160.415115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:03.714397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.680546ms","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-22T06:04:03.714486Z","caller":"traceutil/trace.go:171","msg":"trace[519582182] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2001; }","duration":"176.83336ms","start":"2026-04-22T06:04:03.53763Z","end":"2026-04-22T06:04:03.714464Z","steps":["trace[519582182] 'agreement among raft nodes before linearized reading' (duration: 176.529062ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:04:19.353775Z","caller":"traceutil/trace.go:171","msg":"trace[588949525] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"112.442866ms","start":"2026-04-22T06:04:19.241306Z","end":"2026-04-22T06:04:19.353748Z","steps":["trace[588949525] 'process raft request' (duration: 112.295993ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:04:24.985845Z","caller":"traceutil/trace.go:171","msg":"trace[1037001073] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2226; }","duration":"136.259774ms","start":"2026-04-22T06:04:24.849553Z","end":"2026-04-22T06:04:24.985812Z","steps":["trace[1037001073] 'read index received' (duration: 136.008269ms)","trace[1037001073] 'applied index is now lower than readState.Index' (duration: 250.075µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:04:24.986556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.978779ms","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-22T06:04:24.986619Z","caller":"traceutil/trace.go:171","msg":"trace[1459667081] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2163; }","duration":"137.133092ms","start":"2026-04-22T06:04:24.849468Z","end":"2026-04-22T06:04:24.986601Z","steps":["trace[1459667081] 'agreement among raft nodes before linearized reading' (duration: 136.484348ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:04:24.987146Z","caller":"traceutil/trace.go:171","msg":"trace[1082571458] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"160.529765ms","start":"2026-04-22T06:04:24.826576Z","end":"2026-04-22T06:04:24.987106Z","steps":["trace[1082571458] 'process raft request' (duration: 158.998242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:25.421101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.613271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:04:25.421289Z","caller":"traceutil/trace.go:171","msg":"trace[1504992592] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2164; }","duration":"304.869736ms","start":"2026-04-22T06:04:25.116396Z","end":"2026-04-22T06:04:25.421265Z","steps":["trace[1504992592] 'count revisions from in-memory index tree' (duration: 304.514258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:25.421309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.191637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:04:25.421372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:25.116379Z","time spent":"304.979309ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:04:25.421447Z","caller":"traceutil/trace.go:171","msg":"trace[1679327313] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2164; }","duration":"197.392141ms","start":"2026-04-22T06:04:25.224034Z","end":"2026-04-22T06:04:25.421427Z","steps":["trace[1679327313] 'count revisions from in-memory index tree' (duration: 196.875539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:25.421592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.495947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:04:25.421656Z","caller":"traceutil/trace.go:171","msg":"trace[2068338425] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2164; }","duration":"321.628021ms","start":"2026-04-22T06:04:25.100012Z","end":"2026-04-22T06:04:25.42164Z","steps":["trace[2068338425] 'count revisions from in-memory index tree' (duration: 321.277243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:25.42169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:25.099987Z","time spent":"321.696062ms","remote":"127.0.0.1:42060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:04:25.544758Z","caller":"traceutil/trace.go:171","msg":"trace[1570293754] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2228; }","duration":"106.628408ms","start":"2026-04-22T06:04:25.438112Z","end":"2026-04-22T06:04:25.54474Z","steps":["trace[1570293754] 'read index received' (duration: 105.536034ms)","trace[1570293754] 'applied index is now lower than readState.Index' (duration: 1.091704ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:04:25.544945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.823402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a8989b3879c13d\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-22T06:04:25.544977Z","caller":"traceutil/trace.go:171","msg":"trace[1820391087] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a8989b3879c13d; range_end:; response_count:1; response_revision:2165; }","duration":"106.897654ms","start":"2026-04-22T06:04:25.438071Z","end":"2026-04-22T06:04:25.544968Z","steps":["trace[1820391087] 'agreement among raft nodes before linearized reading' (duration: 106.7409ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:04:25.545646Z","caller":"traceutil/trace.go:171","msg":"trace[719629924] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"168.182096ms","start":"2026-04-22T06:04:25.37685Z","end":"2026-04-22T06:04:25.545032Z","steps":["trace[719629924] 'process raft request' (duration: 166.786886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:25.917449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.543014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:04:25.917613Z","caller":"traceutil/trace.go:171","msg":"trace[209657306] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"370.087715ms","start":"2026-04-22T06:04:25.547505Z","end":"2026-04-22T06:04:25.917593Z","steps":["trace[209657306] 'process raft request' (duration: 155.296976ms)","trace[209657306] 'compare' (duration: 214.361299ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:04:25.917691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:25.547422Z","time spent":"370.223618ms","remote":"127.0.0.1:37562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:04:26.325669Z","caller":"traceutil/trace.go:171","msg":"trace[289891729] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"394.059315ms","start":"2026-04-22T06:04:25.931584Z","end":"2026-04-22T06:04:26.325643Z","steps":["trace[289891729] 'process raft request' (duration: 393.871661ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:04:26.326025Z","caller":"traceutil/trace.go:171","msg":"trace[874729176] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"394.27595ms","start":"2026-04-22T06:04:25.931721Z","end":"2026-04-22T06:04:26.325997Z","steps":["trace[874729176] 'process raft request' (duration: 393.877521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:26.326184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:25.931669Z","time spent":"394.432004ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:04:26.326446Z","caller":"traceutil/trace.go:171","msg":"trace[189835] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"394.663689ms","start":"2026-04-22T06:04:25.931763Z","end":"2026-04-22T06:04:26.326426Z","steps":["trace[189835] 'process raft request' (duration: 394.153648ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:04:26.326613Z","caller":"traceutil/trace.go:171","msg":"trace[1240346174] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"357.675564ms","start":"2026-04-22T06:04:25.968892Z","end":"2026-04-22T06:04:26.326568Z","steps":["trace[1240346174] 'process raft request' (duration: 357.503091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:26.326835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:25.968789Z","time spent":"357.92895ms","remote":"127.0.0.1:37954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16383,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:04:26.326472Z","caller":"traceutil/trace.go:171","msg":"trace[1139570600] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2230; }","duration":"392.41641ms","start":"2026-04-22T06:04:25.934038Z","end":"2026-04-22T06:04:26.326455Z","steps":["trace[1139570600] 'read index received' (duration: 44.964778ms)","trace[1139570600] 'applied index is now lower than readState.Index' (duration: 347.450412ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:04:26.326687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:25.931728Z","time spent":"394.915894ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:04:26.326469Z","caller":"traceutil/trace.go:171","msg":"trace[1137254721] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"392.494562ms","start":"2026-04-22T06:04:25.933958Z","end":"2026-04-22T06:04:26.326453Z","steps":["trace[1137254721] 'process raft request' (duration: 392.357129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:26.327216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.201442ms","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-22T06:04:26.327261Z","caller":"traceutil/trace.go:171","msg":"trace[1355073375] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2172; }","duration":"333.280654ms","start":"2026-04-22T06:04:25.993963Z","end":"2026-04-22T06:04:26.327244Z","steps":["trace[1355073375] 'agreement among raft nodes before linearized reading' (duration: 333.11024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:26.327264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:25.93395Z","time spent":"393.270978ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:04:26.327304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:25.993946Z","time spent":"333.350616ms","remote":"127.0.0.1:38132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4615,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-22T06:04:26.327512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:25.93156Z","time spent":"394.421034ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:04:26.327567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.521333ms","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-22T06:04:26.329212Z","caller":"traceutil/trace.go:171","msg":"trace[1412793431] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2172; }","duration":"395.18362ms","start":"2026-04-22T06:04:25.933999Z","end":"2026-04-22T06:04:26.329183Z","steps":["trace[1412793431] 'agreement among raft nodes before linearized reading' (duration: 392.87662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:04:26.329287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:04:25.933984Z","time spent":"395.290632ms","remote":"127.0.0.1:37584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-22T06:05:23.182528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.50265ms","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-22T06:05:23.18262Z","caller":"traceutil/trace.go:171","msg":"trace[1430175707] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2650; }","duration":"191.621642ms","start":"2026-04-22T06:05:22.990978Z","end":"2026-04-22T06:05:23.1826Z","steps":["trace[1430175707] 'range keys from in-memory index tree' (duration: 191.394698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:23.182786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.932767ms","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-22T06:05:23.182895Z","caller":"traceutil/trace.go:171","msg":"trace[428520810] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2650; }","duration":"244.097759ms","start":"2026-04-22T06:05:22.938774Z","end":"2026-04-22T06:05:23.182871Z","steps":["trace[428520810] 'range keys from in-memory index tree' (duration: 243.660613ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:23.578203Z","caller":"traceutil/trace.go:171","msg":"trace[1588463163] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"391.083635ms","start":"2026-04-22T06:05:23.187079Z","end":"2026-04-22T06:05:23.578163Z","steps":["trace[1588463163] 'process raft request' (duration: 390.804681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:23.578439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:23.187031Z","time spent":"391.312479ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:05:23.578464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.064903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1247"} {"level":"info","ts":"2026-04-22T06:05:23.578527Z","caller":"traceutil/trace.go:171","msg":"trace[1838443631] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2651; }","duration":"389.174265ms","start":"2026-04-22T06:05:23.18934Z","end":"2026-04-22T06:05:23.578515Z","steps":["trace[1838443631] 'agreement among raft nodes before linearized reading' (duration: 388.953271ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:23.578213Z","caller":"traceutil/trace.go:171","msg":"trace[1613003224] linearizableReadLoop","detail":"{readStateIndex:2738; appliedIndex:2737; }","duration":"388.810369ms","start":"2026-04-22T06:05:23.189367Z","end":"2026-04-22T06:05:23.578177Z","steps":["trace[1613003224] 'read index received' (duration: 388.523625ms)","trace[1613003224] 'applied index is now lower than readState.Index' (duration: 208.693µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:05:23.578573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:23.189326Z","time spent":"389.238585ms","remote":"127.0.0.1:37676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1271,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"info","ts":"2026-04-22T06:05:23.581097Z","caller":"traceutil/trace.go:171","msg":"trace[1153066449] transaction","detail":"{read_only:false; response_revision:2652; number_of_response:1; }","duration":"391.367679ms","start":"2026-04-22T06:05:23.189682Z","end":"2026-04-22T06:05:23.581049Z","steps":["trace[1153066449] 'process raft request' (duration: 391.223787ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:23.581154Z","caller":"traceutil/trace.go:171","msg":"trace[1386336876] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2652; }","duration":"100.011381ms","start":"2026-04-22T06:05:23.481109Z","end":"2026-04-22T06:05:23.58112Z","steps":["trace[1386336876] 'agreement among raft nodes before linearized reading' (duration: 99.953181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:23.581163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.236966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-04-22T06:05:23.581361Z","caller":"traceutil/trace.go:171","msg":"trace[2014944062] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2652; }","duration":"158.440959ms","start":"2026-04-22T06:05:23.422893Z","end":"2026-04-22T06:05:23.581334Z","steps":["trace[2014944062] 'agreement among raft nodes before linearized reading' (duration: 158.128264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:23.582414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:23.189667Z","time spent":"392.656259ms","remote":"127.0.0.1:37584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:05:50.99636Z","caller":"traceutil/trace.go:171","msg":"trace[1473726973] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"187.979061ms","start":"2026-04-22T06:05:50.808316Z","end":"2026-04-22T06:05:50.996295Z","steps":["trace[1473726973] 'process raft request' (duration: 187.687317ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:51.006874Z","caller":"traceutil/trace.go:171","msg":"trace[1171251659] linearizableReadLoop","detail":"{readStateIndex:2945; appliedIndex:2943; }","duration":"130.638539ms","start":"2026-04-22T06:05:50.876207Z","end":"2026-04-22T06:05:51.006846Z","steps":["trace[1171251659] 'read index received' (duration: 119.849346ms)","trace[1171251659] 'applied index is now lower than readState.Index' (duration: 10.787803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:05:51.007038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.797702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:05:51.007089Z","caller":"traceutil/trace.go:171","msg":"trace[1965840031] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2845; }","duration":"130.977215ms","start":"2026-04-22T06:05:50.876098Z","end":"2026-04-22T06:05:51.007075Z","steps":["trace[1965840031] 'agreement among raft nodes before linearized reading' (duration: 130.860773ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:51.007181Z","caller":"traceutil/trace.go:171","msg":"trace[480238637] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"186.301917ms","start":"2026-04-22T06:05:50.820842Z","end":"2026-04-22T06:05:51.007143Z","steps":["trace[480238637] 'process raft request' (duration: 185.878371ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:51.575524Z","caller":"traceutil/trace.go:171","msg":"trace[2109810330] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"106.984777ms","start":"2026-04-22T06:05:51.468505Z","end":"2026-04-22T06:05:51.57549Z","steps":["trace[2109810330] 'process raft request' (duration: 106.700183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:51.94984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.643812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:05:51.949931Z","caller":"traceutil/trace.go:171","msg":"trace[1666923279] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2846; }","duration":"162.793124ms","start":"2026-04-22T06:05:51.787115Z","end":"2026-04-22T06:05:51.949908Z","steps":["trace[1666923279] 'count revisions from in-memory index tree' (duration: 162.51666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:51.950062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.736872ms","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-22T06:05:51.950204Z","caller":"traceutil/trace.go:171","msg":"trace[821463977] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2846; }","duration":"211.943305ms","start":"2026-04-22T06:05:51.738237Z","end":"2026-04-22T06:05:51.95018Z","steps":["trace[821463977] 'range keys from in-memory index tree' (duration: 211.499428ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:51.968548Z","caller":"traceutil/trace.go:171","msg":"trace[1104943222] linearizableReadLoop","detail":"{readStateIndex:2947; appliedIndex:2946; }","duration":"109.659525ms","start":"2026-04-22T06:05:51.858855Z","end":"2026-04-22T06:05:51.968515Z","steps":["trace[1104943222] 'read index received' (duration: 109.407261ms)","trace[1104943222] 'applied index is now lower than readState.Index' (duration: 250.594µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:51.968657Z","caller":"traceutil/trace.go:171","msg":"trace[932439960] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"122.045192ms","start":"2026-04-22T06:05:51.846586Z","end":"2026-04-22T06:05:51.968631Z","steps":["trace[932439960] 'process raft request' (duration: 121.724117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:51.968866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.000091ms","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-22T06:05:51.968929Z","caller":"traceutil/trace.go:171","msg":"trace[1102625055] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2847; }","duration":"110.085731ms","start":"2026-04-22T06:05:51.858821Z","end":"2026-04-22T06:05:51.968907Z","steps":["trace[1102625055] 'agreement among raft nodes before linearized reading' (duration: 109.812938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:52.354991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.498239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:05:52.355308Z","caller":"traceutil/trace.go:171","msg":"trace[871050958] linearizableReadLoop","detail":"{readStateIndex:2948; appliedIndex:2947; }","duration":"386.651394ms","start":"2026-04-22T06:05:51.968636Z","end":"2026-04-22T06:05:52.355287Z","steps":["trace[871050958] 'read index received' (duration: 146.552507ms)","trace[871050958] 'applied index is now lower than readState.Index' (duration: 240.097627ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:52.355394Z","caller":"traceutil/trace.go:171","msg":"trace[745194421] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"398.299322ms","start":"2026-04-22T06:05:51.957082Z","end":"2026-04-22T06:05:52.355382Z","steps":["trace[745194421] 'process raft request' (duration: 158.058152ms)","trace[745194421] 'compare' (duration: 239.346917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:05:52.355554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:51.957048Z","time spent":"398.464883ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:05:52.355797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.350139ms","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-22T06:05:52.355893Z","caller":"traceutil/trace.go:171","msg":"trace[1528067098] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2848; }","duration":"381.487071ms","start":"2026-04-22T06:05:51.974383Z","end":"2026-04-22T06:05:52.35587Z","steps":["trace[1528067098] 'agreement among raft nodes before linearized reading' (duration: 381.205867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:52.355936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:51.97437Z","time spent":"381.557832ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-22T06:05:52.356121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.480392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-22T06:05:52.356264Z","caller":"traceutil/trace.go:171","msg":"trace[153638606] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2848; }","duration":"489.686476ms","start":"2026-04-22T06:05:51.866551Z","end":"2026-04-22T06:05:52.356237Z","steps":["trace[153638606] 'agreement among raft nodes before linearized reading' (duration: 489.25654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:52.356336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:51.866538Z","time spent":"489.786257ms","remote":"127.0.0.1:37584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-22T06:05:52.695228Z","caller":"traceutil/trace.go:171","msg":"trace[87051685] linearizableReadLoop","detail":"{readStateIndex:2950; appliedIndex:2948; }","duration":"269.472175ms","start":"2026-04-22T06:05:52.425732Z","end":"2026-04-22T06:05:52.695204Z","steps":["trace[87051685] 'read index received' (duration: 265.803933ms)","trace[87051685] 'applied index is now lower than readState.Index' (duration: 3.666712ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:52.695353Z","caller":"traceutil/trace.go:171","msg":"trace[870948029] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"331.864133ms","start":"2026-04-22T06:05:52.363477Z","end":"2026-04-22T06:05:52.695341Z","steps":["trace[870948029] 'process raft request' (duration: 331.64183ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:52.695307Z","caller":"traceutil/trace.go:171","msg":"trace[937933889] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"334.646893ms","start":"2026-04-22T06:05:52.36062Z","end":"2026-04-22T06:05:52.695267Z","steps":["trace[937933889] 'process raft request' (duration: 330.847249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:52.695462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:52.363448Z","time spent":"331.934335ms","remote":"127.0.0.1:37584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:05:52.695505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:52.360603Z","time spent":"334.800175ms","remote":"127.0.0.1:37584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:05:52.695796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.064474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-22T06:05:52.695868Z","caller":"traceutil/trace.go:171","msg":"trace[685405504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2850; }","duration":"270.194966ms","start":"2026-04-22T06:05:52.425655Z","end":"2026-04-22T06:05:52.69585Z","steps":["trace[685405504] 'agreement among raft nodes before linearized reading' (duration: 269.865691ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:48.778893Z","caller":"traceutil/trace.go:171","msg":"trace[29430371] transaction","detail":"{read_only:false; response_revision:3321; number_of_response:1; }","duration":"119.807179ms","start":"2026-04-22T06:06:48.659048Z","end":"2026-04-22T06:06:48.778856Z","steps":["trace[29430371] 'process raft request' (duration: 102.335615ms)","trace[29430371] 'compare' (duration: 17.23692ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:11.20734Z","caller":"traceutil/trace.go:171","msg":"trace[193253028] transaction","detail":"{read_only:false; response_revision:4283; number_of_response:1; }","duration":"102.713823ms","start":"2026-04-22T06:08:11.104602Z","end":"2026-04-22T06:08:11.207316Z","steps":["trace[193253028] 'process raft request' (duration: 99.610035ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:16.416218Z","caller":"traceutil/trace.go:171","msg":"trace[1850175298] transaction","detail":"{read_only:false; response_revision:4455; number_of_response:1; }","duration":"104.226876ms","start":"2026-04-22T06:08:16.311962Z","end":"2026-04-22T06:08:16.416188Z","steps":["trace[1850175298] 'process raft request' (duration: 103.931194ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:16.424009Z","caller":"traceutil/trace.go:171","msg":"trace[542938112] transaction","detail":"{read_only:false; response_revision:4456; number_of_response:1; }","duration":"111.664294ms","start":"2026-04-22T06:08:16.312329Z","end":"2026-04-22T06:08:16.423994Z","steps":["trace[542938112] 'process raft request' (duration: 110.888335ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:16.424363Z","caller":"traceutil/trace.go:171","msg":"trace[1999429982] transaction","detail":"{read_only:false; response_revision:4457; number_of_response:1; }","duration":"111.785809ms","start":"2026-04-22T06:08:16.312556Z","end":"2026-04-22T06:08:16.424341Z","steps":["trace[1999429982] 'process raft request' (duration: 110.842734ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:17.611064Z","caller":"traceutil/trace.go:171","msg":"trace[1754457922] transaction","detail":"{read_only:false; response_revision:4462; number_of_response:1; }","duration":"203.241853ms","start":"2026-04-22T06:08:17.40777Z","end":"2026-04-22T06:08:17.611012Z","steps":["trace[1754457922] 'process raft request' (duration: 202.993683ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:17.645274Z","caller":"traceutil/trace.go:171","msg":"trace[1818769408] linearizableReadLoop","detail":"{readStateIndex:4645; appliedIndex:4643; }","duration":"136.870827ms","start":"2026-04-22T06:08:17.508368Z","end":"2026-04-22T06:08:17.645238Z","steps":["trace[1818769408] 'read index received' (duration: 102.407401ms)","trace[1818769408] 'applied index is now lower than readState.Index' (duration: 34.461916ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:17.645365Z","caller":"traceutil/trace.go:171","msg":"trace[928205758] transaction","detail":"{read_only:false; response_revision:4463; number_of_response:1; }","duration":"155.227032ms","start":"2026-04-22T06:08:17.490105Z","end":"2026-04-22T06:08:17.645332Z","steps":["trace[928205758] 'process raft request' (duration: 154.934951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:17.645706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.340454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-befa48a4-9d95-4d8b-aa60-32e2fea2dca4\" ","response":"range_response_count:1 size:4049"} {"level":"info","ts":"2026-04-22T06:08:17.645793Z","caller":"traceutil/trace.go:171","msg":"trace[1740366747] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-befa48a4-9d95-4d8b-aa60-32e2fea2dca4; range_end:; response_count:1; response_revision:4463; }","duration":"137.493331ms","start":"2026-04-22T06:08:17.508277Z","end":"2026-04-22T06:08:17.64577Z","steps":["trace[1740366747] 'agreement among raft nodes before linearized reading' (duration: 137.306024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:17.646343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.450403ms","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-22T06:08:17.64888Z","caller":"traceutil/trace.go:171","msg":"trace[1023096652] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4463; }","duration":"114.116538ms","start":"2026-04-22T06:08:17.532285Z","end":"2026-04-22T06:08:17.646401Z","steps":["trace[1023096652] 'agreement among raft nodes before linearized reading' (duration: 113.336019ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:18.738274Z","caller":"traceutil/trace.go:171","msg":"trace[127353467] transaction","detail":"{read_only:false; response_revision:4511; number_of_response:1; }","duration":"118.548933ms","start":"2026-04-22T06:08:18.619693Z","end":"2026-04-22T06:08:18.738242Z","steps":["trace[127353467] 'process raft request' (duration: 118.481021ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:18.738462Z","caller":"traceutil/trace.go:171","msg":"trace[722571665] transaction","detail":"{read_only:false; response_revision:4510; number_of_response:1; }","duration":"195.865746ms","start":"2026-04-22T06:08:18.542563Z","end":"2026-04-22T06:08:18.738428Z","steps":["trace[722571665] 'process raft request' (duration: 192.235712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:18.738836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.766885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-22T06:08:18.738584Z","caller":"traceutil/trace.go:171","msg":"trace[1241295625] linearizableReadLoop","detail":"{readStateIndex:4696; appliedIndex:4695; }","duration":"194.488845ms","start":"2026-04-22T06:08:18.544037Z","end":"2026-04-22T06:08:18.738526Z","steps":["trace[1241295625] 'read index received' (duration: 190.86678ms)","trace[1241295625] 'applied index is now lower than readState.Index' (duration: 3.615895ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:18.739002Z","caller":"traceutil/trace.go:171","msg":"trace[56060221] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4511; }","duration":"194.965913ms","start":"2026-04-22T06:08:18.544016Z","end":"2026-04-22T06:08:18.738982Z","steps":["trace[56060221] 'agreement among raft nodes before linearized reading' (duration: 194.592239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:18.739674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.082553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-22T06:08:18.739731Z","caller":"traceutil/trace.go:171","msg":"trace[320204937] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:4511; }","duration":"119.185667ms","start":"2026-04-22T06:08:18.620532Z","end":"2026-04-22T06:08:18.739718Z","steps":["trace[320204937] 'agreement among raft nodes before linearized reading' (duration: 118.912697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:18.74057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.500672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2348456"} {"level":"info","ts":"2026-04-22T06:08:18.740656Z","caller":"traceutil/trace.go:171","msg":"trace[1390323555] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4511; }","duration":"132.649938ms","start":"2026-04-22T06:08:18.607989Z","end":"2026-04-22T06:08:18.740639Z","steps":["trace[1390323555] 'agreement among raft nodes before linearized reading' (duration: 131.11164ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:19.841989Z","caller":"traceutil/trace.go:171","msg":"trace[59447869] transaction","detail":"{read_only:false; response_revision:4533; number_of_response:1; }","duration":"347.576377ms","start":"2026-04-22T06:08:19.494379Z","end":"2026-04-22T06:08:19.841955Z","steps":["trace[59447869] 'process raft request' (duration: 347.290147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:19.842165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:19.494348Z","time spent":"347.723823ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16216,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:19.842197Z","caller":"traceutil/trace.go:171","msg":"trace[940528145] linearizableReadLoop","detail":"{readStateIndex:4720; appliedIndex:4719; }","duration":"337.173832ms","start":"2026-04-22T06:08:19.504989Z","end":"2026-04-22T06:08:19.842163Z","steps":["trace[940528145] 'read index received' (duration: 335.75698ms)","trace[940528145] 'applied index is now lower than readState.Index' (duration: 1.415332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:19.842494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.470594ms","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-22T06:08:19.842549Z","caller":"traceutil/trace.go:171","msg":"trace[689588675] 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:4534; }","duration":"337.577998ms","start":"2026-04-22T06:08:19.504951Z","end":"2026-04-22T06:08:19.842529Z","steps":["trace[689588675] 'agreement among raft nodes before linearized reading' (duration: 337.38561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:19.842593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:19.504937Z","time spent":"337.64649ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-04-22T06:08:19.842746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.273933ms","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-22T06:08:19.8428Z","caller":"traceutil/trace.go:171","msg":"trace[453011997] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4534; }","duration":"225.382657ms","start":"2026-04-22T06:08:19.617405Z","end":"2026-04-22T06:08:19.842788Z","steps":["trace[453011997] 'agreement among raft nodes before linearized reading' (duration: 225.219981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:19.843006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.553687ms","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-22T06:08:19.843042Z","caller":"traceutil/trace.go:171","msg":"trace[1742846987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4534; }","duration":"174.609449ms","start":"2026-04-22T06:08:19.668424Z","end":"2026-04-22T06:08:19.843034Z","steps":["trace[1742846987] 'agreement among raft nodes before linearized reading' (duration: 174.518026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:19.843228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.240263ms","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-22T06:08:19.843279Z","caller":"traceutil/trace.go:171","msg":"trace[1796613883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4534; }","duration":"178.309436ms","start":"2026-04-22T06:08:19.664956Z","end":"2026-04-22T06:08:19.843265Z","steps":["trace[1796613883] 'agreement among raft nodes before linearized reading' (duration: 178.203272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:19.843282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.555687ms","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-22T06:08:19.843412Z","caller":"traceutil/trace.go:171","msg":"trace[221721222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4534; }","duration":"174.733204ms","start":"2026-04-22T06:08:19.668641Z","end":"2026-04-22T06:08:19.843374Z","steps":["trace[221721222] 'agreement among raft nodes before linearized reading' (duration: 174.290818ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:25.34739Z","caller":"traceutil/trace.go:171","msg":"trace[2067452882] linearizableReadLoop","detail":"{readStateIndex:4842; appliedIndex:4841; }","duration":"478.227396ms","start":"2026-04-22T06:08:24.869134Z","end":"2026-04-22T06:08:25.347362Z","steps":["trace[2067452882] 'read index received' (duration: 477.975027ms)","trace[2067452882] 'applied index is now lower than readState.Index' (duration: 251.039µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:25.347719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.560178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f014fba3-e4bd-4cb4-ade7-d58ae0282b9b\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-22T06:08:25.34783Z","caller":"traceutil/trace.go:171","msg":"trace[1319783034] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f014fba3-e4bd-4cb4-ade7-d58ae0282b9b; range_end:; response_count:1; response_revision:4652; }","duration":"478.743955ms","start":"2026-04-22T06:08:24.869063Z","end":"2026-04-22T06:08:25.347807Z","steps":["trace[1319783034] 'agreement among raft nodes before linearized reading' (duration: 478.409943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:25.34789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:24.869037Z","time spent":"478.841529ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f014fba3-e4bd-4cb4-ade7-d58ae0282b9b\" "} {"level":"info","ts":"2026-04-22T06:08:25.34823Z","caller":"traceutil/trace.go:171","msg":"trace[1457896396] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"591.041202ms","start":"2026-04-22T06:08:24.756393Z","end":"2026-04-22T06:08:25.347434Z","steps":["trace[1457896396] 'process raft request' (duration: 590.787423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:25.34829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.494213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:08:25.348355Z","caller":"traceutil/trace.go:171","msg":"trace[818667547] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:4652; }","duration":"118.612127ms","start":"2026-04-22T06:08:25.22972Z","end":"2026-04-22T06:08:25.348332Z","steps":["trace[818667547] 'agreement among raft nodes before linearized reading' (duration: 118.462272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:25.348407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:24.756367Z","time spent":"591.948845ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:08:25.962704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.961822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-04-22T06:08:25.96277Z","caller":"traceutil/trace.go:171","msg":"trace[225547881] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4655; }","duration":"154.087287ms","start":"2026-04-22T06:08:25.808667Z","end":"2026-04-22T06:08:25.962754Z","steps":["trace[225547881] 'range keys from in-memory index tree' (duration: 153.234686ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:26.392019Z","caller":"traceutil/trace.go:171","msg":"trace[958269319] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"163.605316ms","start":"2026-04-22T06:08:26.228392Z","end":"2026-04-22T06:08:26.391998Z","steps":["trace[958269319] 'process raft request' (duration: 77.4114ms)","trace[958269319] 'compare' (duration: 85.75509ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:37.969127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.376751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:08:37.96927Z","caller":"traceutil/trace.go:171","msg":"trace[1055430411] linearizableReadLoop","detail":"{readStateIndex:5034; appliedIndex:5033; }","duration":"428.43684ms","start":"2026-04-22T06:08:37.5408Z","end":"2026-04-22T06:08:37.969237Z","steps":["trace[1055430411] 'read index received' (duration: 43.711µs)","trace[1055430411] 'applied index is now lower than readState.Index' (duration: 428.391789ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:37.969381Z","caller":"traceutil/trace.go:171","msg":"trace[1056070192] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"494.473168ms","start":"2026-04-22T06:08:37.474896Z","end":"2026-04-22T06:08:37.969369Z","steps":["trace[1056070192] 'process raft request' (duration: 39.754415ms)","trace[1056070192] 'compare' (duration: 454.222626ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:37.969433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:37.474873Z","time spent":"494.53829ms","remote":"127.0.0.1:37562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":901,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:08:37.969799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.9939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-xrtjq\" ","response":"range_response_count:1 size:9897"} {"level":"info","ts":"2026-04-22T06:08:37.96983Z","caller":"traceutil/trace.go:171","msg":"trace[1513782153] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-xrtjq; range_end:; response_count:1; response_revision:4838; }","duration":"429.067702ms","start":"2026-04-22T06:08:37.540753Z","end":"2026-04-22T06:08:37.969821Z","steps":["trace[1513782153] 'agreement among raft nodes before linearized reading' (duration: 428.869485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:37.969853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:37.540736Z","time spent":"429.112734ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9921,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-xrtjq\" "} {"level":"warn","ts":"2026-04-22T06:08:37.970038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.91286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-22T06:08:37.970064Z","caller":"traceutil/trace.go:171","msg":"trace[1049372992] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4838; }","duration":"341.992852ms","start":"2026-04-22T06:08:37.628061Z","end":"2026-04-22T06:08:37.970054Z","steps":["trace[1049372992] 'agreement among raft nodes before linearized reading' (duration: 341.92433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:37.970086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:37.628025Z","time spent":"342.053764ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-22T06:08:37.97061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.165565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:08:37.970722Z","caller":"traceutil/trace.go:171","msg":"trace[1481238997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4838; }","duration":"318.32283ms","start":"2026-04-22T06:08:37.652372Z","end":"2026-04-22T06:08:37.970695Z","steps":["trace[1481238997] 'agreement among raft nodes before linearized reading' (duration: 317.707079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:37.970775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:37.652362Z","time spent":"318.404853ms","remote":"127.0.0.1:37488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T06:08:38.402942Z","caller":"traceutil/trace.go:171","msg":"trace[131683158] linearizableReadLoop","detail":"{readStateIndex:5035; appliedIndex:5034; }","duration":"402.35744ms","start":"2026-04-22T06:08:38.000559Z","end":"2026-04-22T06:08:38.402916Z","steps":["trace[131683158] 'read index received' (duration: 402.163213ms)","trace[131683158] 'applied index is now lower than readState.Index' (duration: 192.867µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:38.403207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.618609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-befa48a4-9d95-4d8b-aa60-32e2fea2dca4\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-22T06:08:38.403312Z","caller":"traceutil/trace.go:171","msg":"trace[928548500] range","detail":"{range_begin:/registry/persistentvolumes/pvc-befa48a4-9d95-4d8b-aa60-32e2fea2dca4; range_end:; response_count:1; response_revision:4839; }","duration":"402.773425ms","start":"2026-04-22T06:08:38.000514Z","end":"2026-04-22T06:08:38.403287Z","steps":["trace[928548500] 'agreement among raft nodes before linearized reading' (duration: 402.498935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:38.403385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.461181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-04-22T06:08:38.403381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:38.000467Z","time spent":"402.890938ms","remote":"127.0.0.1:37616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1459,"request content":"key:\"/registry/persistentvolumes/pvc-befa48a4-9d95-4d8b-aa60-32e2fea2dca4\" "} {"level":"warn","ts":"2026-04-22T06:08:38.403362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.719688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-22T06:08:38.403446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.497802ms","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-22T06:08:38.403473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.105302ms","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-22T06:08:38.403539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.81584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-22T06:08:38.403542Z","caller":"traceutil/trace.go:171","msg":"trace[1472788776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4839; }","duration":"220.951537ms","start":"2026-04-22T06:08:38.182537Z","end":"2026-04-22T06:08:38.403488Z","steps":["trace[1472788776] 'agreement among raft nodes before linearized reading' (duration: 220.629976ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:38.403536Z","caller":"traceutil/trace.go:171","msg":"trace[980366111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4839; }","duration":"225.633866ms","start":"2026-04-22T06:08:38.177881Z","end":"2026-04-22T06:08:38.403515Z","steps":["trace[980366111] 'agreement among raft nodes before linearized reading' (duration: 225.384428ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:38.403574Z","caller":"traceutil/trace.go:171","msg":"trace[400000440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4839; }","duration":"241.893382ms","start":"2026-04-22T06:08:38.161674Z","end":"2026-04-22T06:08:38.403567Z","steps":["trace[400000440] 'agreement among raft nodes before linearized reading' (duration: 241.804039ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:38.403593Z","caller":"traceutil/trace.go:171","msg":"trace[1948357992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4839; }","duration":"233.269037ms","start":"2026-04-22T06:08:38.170297Z","end":"2026-04-22T06:08:38.403566Z","steps":["trace[1948357992] 'agreement among raft nodes before linearized reading' (duration: 232.912655ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:38.403446Z","caller":"traceutil/trace.go:171","msg":"trace[575765649] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4839; }","duration":"380.599926ms","start":"2026-04-22T06:08:38.02281Z","end":"2026-04-22T06:08:38.40341Z","steps":["trace[575765649] 'agreement among raft nodes before linearized reading' (duration: 380.248054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:38.403786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.808488ms","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-22T06:08:38.403804Z","caller":"traceutil/trace.go:171","msg":"trace[702263192] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4839; }","duration":"169.835318ms","start":"2026-04-22T06:08:38.233963Z","end":"2026-04-22T06:08:38.403799Z","steps":["trace[702263192] 'agreement among raft nodes before linearized reading' (duration: 169.777416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:38.403816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:38.022789Z","time spent":"381.00948ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-22T06:08:38.403845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.34957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T06:08:38.403876Z","caller":"traceutil/trace.go:171","msg":"trace[911164171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4839; }","duration":"171.414973ms","start":"2026-04-22T06:08:38.232451Z","end":"2026-04-22T06:08:38.403866Z","steps":["trace[911164171] 'agreement among raft nodes before linearized reading' (duration: 171.325439ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:38.404033Z","caller":"traceutil/trace.go:171","msg":"trace[34857561] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"405.245399ms","start":"2026-04-22T06:08:37.998764Z","end":"2026-04-22T06:08:38.404009Z","steps":["trace[34857561] 'process raft request' (duration: 404.002007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:38.404205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:37.998746Z","time spent":"405.351742ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9709,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:08:38.404888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.672449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:08:38.404949Z","caller":"traceutil/trace.go:171","msg":"trace[624281314] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:4839; }","duration":"242.799903ms","start":"2026-04-22T06:08:38.162134Z","end":"2026-04-22T06:08:38.404934Z","steps":["trace[624281314] 'agreement among raft nodes before linearized reading' (duration: 241.326023ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:38.58649Z","caller":"traceutil/trace.go:171","msg":"trace[1019375676] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"150.185677ms","start":"2026-04-22T06:08:38.436266Z","end":"2026-04-22T06:08:38.586452Z","steps":["trace[1019375676] 'process raft request' (duration: 149.969119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:38.842327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.202006ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-22T06:08:38.842429Z","caller":"traceutil/trace.go:171","msg":"trace[777342908] linearizableReadLoop","detail":"{readStateIndex:5049; appliedIndex:5048; }","duration":"197.09809ms","start":"2026-04-22T06:08:38.645315Z","end":"2026-04-22T06:08:38.842413Z","steps":["trace[777342908] 'read index received' (duration: 8.797081ms)","trace[777342908] 'applied index is now lower than readState.Index' (duration: 188.299409ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:38.842992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.533406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-04-22T06:08:38.843021Z","caller":"traceutil/trace.go:171","msg":"trace[1020315080] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4852; }","duration":"197.736923ms","start":"2026-04-22T06:08:38.645274Z","end":"2026-04-22T06:08:38.84301Z","steps":["trace[1020315080] 'agreement among raft nodes before linearized reading' (duration: 197.444993ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:38.867692Z","caller":"traceutil/trace.go:171","msg":"trace[845584358] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"221.171084ms","start":"2026-04-22T06:08:38.646506Z","end":"2026-04-22T06:08:38.867677Z","steps":["trace[845584358] 'process raft request' (duration: 220.647896ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:38.867964Z","caller":"traceutil/trace.go:171","msg":"trace[1205008345] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"220.695758ms","start":"2026-04-22T06:08:38.647256Z","end":"2026-04-22T06:08:38.867952Z","steps":["trace[1205008345] 'process raft request' (duration: 220.055486ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:38.868117Z","caller":"traceutil/trace.go:171","msg":"trace[745731303] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"220.47559ms","start":"2026-04-22T06:08:38.647629Z","end":"2026-04-22T06:08:38.868104Z","steps":["trace[745731303] 'process raft request' (duration: 219.748975ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:38.868309Z","caller":"traceutil/trace.go:171","msg":"trace[1808627945] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"220.1897ms","start":"2026-04-22T06:08:38.648107Z","end":"2026-04-22T06:08:38.868297Z","steps":["trace[1808627945] 'process raft request' (duration: 219.31359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:38.868502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.065209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-22T06:08:38.868541Z","caller":"traceutil/trace.go:171","msg":"trace[153024097] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4856; }","duration":"128.156682ms","start":"2026-04-22T06:08:38.740375Z","end":"2026-04-22T06:08:38.868532Z","steps":["trace[153024097] 'agreement among raft nodes before linearized reading' (duration: 128.050489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:38.868755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.852103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-22T06:08:38.868796Z","caller":"traceutil/trace.go:171","msg":"trace[1975089388] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4856; }","duration":"170.936536ms","start":"2026-04-22T06:08:38.697849Z","end":"2026-04-22T06:08:38.868785Z","steps":["trace[1975089388] 'agreement among raft nodes before linearized reading' (duration: 170.837863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:38.868995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.299234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-22T06:08:38.869026Z","caller":"traceutil/trace.go:171","msg":"trace[1215322986] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4856; }","duration":"182.364377ms","start":"2026-04-22T06:08:38.686653Z","end":"2026-04-22T06:08:38.869018Z","steps":["trace[1215322986] 'agreement among raft nodes before linearized reading' (duration: 182.278434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:38.869392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.892496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f014fba3-e4bd-4cb4-ade7-d58ae0282b9b\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-22T06:08:38.869486Z","caller":"traceutil/trace.go:171","msg":"trace[872670564] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f014fba3-e4bd-4cb4-ade7-d58ae0282b9b; range_end:; response_count:1; response_revision:4856; }","duration":"227.068575ms","start":"2026-04-22T06:08:38.642395Z","end":"2026-04-22T06:08:38.869464Z","steps":["trace[872670564] 'agreement among raft nodes before linearized reading' (duration: 226.660561ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:35.544595Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3231} {"level":"info","ts":"2026-04-22T06:11:35.652266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3231,"took":"106.043264ms","hash":2717812226,"current-db-size-bytes":58769408,"current-db-size":"59 MB","current-db-size-in-use-bytes":31272960,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-22T06:11:35.652332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2717812226,"revision":3231,"compact-revision":-1} {"level":"warn","ts":"2026-04-22T06:12:07.858348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.940054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:12:07.858468Z","caller":"traceutil/trace.go:171","msg":"trace[1276115324] linearizableReadLoop","detail":"{readStateIndex:6963; appliedIndex:6961; }","duration":"200.239364ms","start":"2026-04-22T06:12:07.658209Z","end":"2026-04-22T06:12:07.858449Z","steps":["trace[1276115324] 'read index received' (duration: 43.166582ms)","trace[1276115324] 'applied index is now lower than readState.Index' (duration: 157.071502ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:12:07.858544Z","caller":"traceutil/trace.go:171","msg":"trace[1824957589] transaction","detail":"{read_only:false; response_revision:6676; number_of_response:1; }","duration":"209.768597ms","start":"2026-04-22T06:12:07.648749Z","end":"2026-04-22T06:12:07.858517Z","steps":["trace[1824957589] 'process raft request' (duration: 82.559367ms)","trace[1824957589] 'compare' (duration: 126.818362ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:12:07.858693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.206783ms","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-22T06:12:07.858728Z","caller":"traceutil/trace.go:171","msg":"trace[963641784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6676; }","duration":"197.321166ms","start":"2026-04-22T06:12:07.661397Z","end":"2026-04-22T06:12:07.858718Z","steps":["trace[963641784] 'agreement among raft nodes before linearized reading' (duration: 197.153382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:12:07.858748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.51188ms","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-22T06:12:07.858839Z","caller":"traceutil/trace.go:171","msg":"trace[1977517641] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:6676; }","duration":"200.629822ms","start":"2026-04-22T06:12:07.658187Z","end":"2026-04-22T06:12:07.858817Z","steps":["trace[1977517641] 'agreement among raft nodes before linearized reading' (duration: 200.331646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:12:07.85896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.136491ms","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-22T06:12:07.858996Z","caller":"traceutil/trace.go:171","msg":"trace[558020166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6676; }","duration":"197.194773ms","start":"2026-04-22T06:12:07.66179Z","end":"2026-04-22T06:12:07.858985Z","steps":["trace[558020166] 'agreement among raft nodes before linearized reading' (duration: 197.04077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:12:07.859038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.397767ms","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-22T06:12:07.85907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.329471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:12:07.859072Z","caller":"traceutil/trace.go:171","msg":"trace[442749907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6676; }","duration":"197.454798ms","start":"2026-04-22T06:12:07.661605Z","end":"2026-04-22T06:12:07.85906Z","steps":["trace[442749907] 'agreement among raft nodes before linearized reading' (duration: 197.365916ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:12:07.859095Z","caller":"traceutil/trace.go:171","msg":"trace[434026088] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:6676; }","duration":"174.375572ms","start":"2026-04-22T06:12:07.684709Z","end":"2026-04-22T06:12:07.859085Z","steps":["trace[434026088] 'agreement among raft nodes before linearized reading' (duration: 174.338811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:12:48.217683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.223448ms","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-22T06:12:48.217906Z","caller":"traceutil/trace.go:171","msg":"trace[1102172715] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6997; }","duration":"158.487524ms","start":"2026-04-22T06:12:48.059391Z","end":"2026-04-22T06:12:48.217879Z","steps":["trace[1102172715] 'range keys from in-memory index tree' (duration: 157.842931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:12:48.218431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.777259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T06:12:48.218527Z","caller":"traceutil/trace.go:171","msg":"trace[527183385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6997; }","duration":"131.879201ms","start":"2026-04-22T06:12:48.086624Z","end":"2026-04-22T06:12:48.218504Z","steps":["trace[527183385] 'range keys from in-memory index tree' (duration: 131.652327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:12:48.218805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.057695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-22T06:12:48.218842Z","caller":"traceutil/trace.go:171","msg":"trace[1815540820] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6997; }","duration":"159.090955ms","start":"2026-04-22T06:12:48.059738Z","end":"2026-04-22T06:12:48.218829Z","steps":["trace[1815540820] 'range keys from in-memory index tree' (duration: 158.949323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:12:48.219105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.94327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-22T06:12:48.21918Z","caller":"traceutil/trace.go:171","msg":"trace[804944650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6997; }","duration":"149.078993ms","start":"2026-04-22T06:12:48.070081Z","end":"2026-04-22T06:12:48.21916Z","steps":["trace[804944650] 'range keys from in-memory index tree' (duration: 148.649604ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:12:48.353266Z","caller":"traceutil/trace.go:171","msg":"trace[1296856997] transaction","detail":"{read_only:false; response_revision:7004; number_of_response:1; }","duration":"123.990289ms","start":"2026-04-22T06:12:48.229216Z","end":"2026-04-22T06:12:48.353206Z","steps":["trace[1296856997] 'process raft request' (duration: 123.859157ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:12:48.353623Z","caller":"traceutil/trace.go:171","msg":"trace[1365416673] transaction","detail":"{read_only:false; response_revision:7000; number_of_response:1; }","duration":"124.777164ms","start":"2026-04-22T06:12:48.228782Z","end":"2026-04-22T06:12:48.353559Z","steps":["trace[1365416673] 'process raft request' (duration: 124.136442ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:12:48.353706Z","caller":"traceutil/trace.go:171","msg":"trace[1942773094] transaction","detail":"{read_only:false; response_revision:7001; number_of_response:1; }","duration":"124.832045ms","start":"2026-04-22T06:12:48.228829Z","end":"2026-04-22T06:12:48.353661Z","steps":["trace[1942773094] 'process raft request' (duration: 124.147222ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:12:48.353756Z","caller":"traceutil/trace.go:171","msg":"trace[1976355631] transaction","detail":"{read_only:false; response_revision:6999; number_of_response:1; }","duration":"125.322025ms","start":"2026-04-22T06:12:48.228327Z","end":"2026-04-22T06:12:48.353649Z","steps":["trace[1976355631] 'process raft request' (duration: 90.165008ms)","trace[1976355631] 'compare' (duration: 34.141137ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:12:48.353986Z","caller":"traceutil/trace.go:171","msg":"trace[92057608] transaction","detail":"{read_only:false; response_revision:7002; number_of_response:1; }","duration":"124.773884ms","start":"2026-04-22T06:12:48.228876Z","end":"2026-04-22T06:12:48.35365Z","steps":["trace[92057608] 'process raft request' (duration: 124.133542ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:12:48.354131Z","caller":"traceutil/trace.go:171","msg":"trace[1856768689] transaction","detail":"{read_only:false; response_revision:7003; number_of_response:1; }","duration":"125.098471ms","start":"2026-04-22T06:12:48.22902Z","end":"2026-04-22T06:12:48.354118Z","steps":["trace[1856768689] 'process raft request' (duration: 124.02457ms)"],"step_count":1}