{"level":"warn","ts":"2026-04-13T20:59:47.027715Z","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-13T20:59:47.027806Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-13T20:59:47.027923Z","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-13T20:59:47.027938Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-13T20:59:47.027954Z","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-13T20:59:47.028659Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-13T20:59:47.028747Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-13T20:59:47.034698Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.625741ms"} {"level":"info","ts":"2026-04-13T20:59:47.041138Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-13T20:59:47.0412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T20:59:47.041222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-13T20:59:47.041235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T20:59:47.041245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-13T20:59:47.041278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-13T20:59:47.049844Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T20:59:47.050987Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T20:59:47.052973Z","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-13T20:59:47.055182Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T20:59:47.055661Z","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-13T20:59:47.055776Z","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-13T20:59:47.055805Z","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-13T20:59:47.05599Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T20:59:47.05672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-13T20:59:47.056848Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-13T20:59:47.060679Z","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-13T20:59:47.060919Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T20:59:47.060965Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T20:59:47.061108Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-13T20:59:47.06114Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-13T20:59:47.844568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T20:59:47.844641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T20:59:47.844662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-13T20:59:47.84468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-13T20:59:47.844691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-13T20:59:47.844714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-13T20:59:47.844727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-13T20:59:47.846745Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:59:47.848176Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:59:47.848257Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:59:47.848281Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:59:47.848314Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T20:59:47.848341Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T20:59:47.84838Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T20:59:47.848651Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T20:59:47.848679Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T20:59:47.851138Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-04-13T20:59:47.851731Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T20:59:50.438666Z","caller":"traceutil/trace.go:171","msg":"trace[810306427] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"430.72014ms","start":"2026-04-13T20:59:50.007918Z","end":"2026-04-13T20:59:50.438638Z","steps":["trace[810306427] 'read index received' (duration: 430.527245ms)","trace[810306427] 'applied index is now lower than readState.Index' (duration: 191.855µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:59:50.438785Z","caller":"traceutil/trace.go:171","msg":"trace[1174090043] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"432.184437ms","start":"2026-04-13T20:59:50.00659Z","end":"2026-04-13T20:59:50.438775Z","steps":["trace[1174090043] 'process raft request' (duration: 431.90796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:59:50.439019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.088296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-13T20:59:50.439057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.118171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-13T20:59:50.439142Z","caller":"traceutil/trace.go:171","msg":"trace[1710522361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"332.25454ms","start":"2026-04-13T20:59:50.106866Z","end":"2026-04-13T20:59:50.43912Z","steps":["trace[1710522361] 'agreement among raft nodes before linearized reading' (duration: 332.031324ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:59:50.439149Z","caller":"traceutil/trace.go:171","msg":"trace[1151188491] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"431.234663ms","start":"2026-04-13T20:59:50.007898Z","end":"2026-04-13T20:59:50.439132Z","steps":["trace[1151188491] 'agreement among raft nodes before linearized reading' (duration: 431.033358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:59:50.439184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:59:50.007889Z","time spent":"431.286894ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-04-13T20:59:50.439184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:59:50.10678Z","time spent":"332.394643ms","remote":"127.0.0.1:41210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T20:59:50.439253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:59:50.006572Z","time spent":"432.240128ms","remote":"127.0.0.1:41510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:59:50.788328Z","caller":"traceutil/trace.go:171","msg":"trace[279538840] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"342.144298ms","start":"2026-04-13T20:59:50.446159Z","end":"2026-04-13T20:59:50.788303Z","steps":["trace[279538840] 'read index received' (duration: 319.036216ms)","trace[279538840] 'applied index is now lower than readState.Index' (duration: 23.107312ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:59:50.788489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.307002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-13T20:59:50.788521Z","caller":"traceutil/trace.go:171","msg":"trace[1181333910] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:73; }","duration":"342.358093ms","start":"2026-04-13T20:59:50.446155Z","end":"2026-04-13T20:59:50.788513Z","steps":["trace[1181333910] 'agreement among raft nodes before linearized reading' (duration: 342.255331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:59:50.788546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:59:50.446125Z","time spent":"342.415365ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"info","ts":"2026-04-13T20:59:50.788681Z","caller":"traceutil/trace.go:171","msg":"trace[599064774] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"343.690477ms","start":"2026-04-13T20:59:50.444962Z","end":"2026-04-13T20:59:50.788652Z","steps":["trace[599064774] 'process raft request' (duration: 320.111083ms)","trace[599064774] 'compare' (duration: 23.134132ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:59:50.788799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:59:50.444944Z","time spent":"343.7961ms","remote":"127.0.0.1:41510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:59:50.836177Z","caller":"traceutil/trace.go:171","msg":"trace[1515437455] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"361.154666ms","start":"2026-04-13T20:59:50.474998Z","end":"2026-04-13T20:59:50.836153Z","steps":["trace[1515437455] 'process raft request' (duration: 360.970381ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:59:50.836208Z","caller":"traceutil/trace.go:171","msg":"trace[2101613386] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"210.483401ms","start":"2026-04-13T20:59:50.625696Z","end":"2026-04-13T20:59:50.836179Z","steps":["trace[2101613386] 'process raft request' (duration: 210.398198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:59:50.836293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:59:50.474976Z","time spent":"361.266159ms","remote":"127.0.0.1:41360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:00:04.344668Z","caller":"traceutil/trace.go:171","msg":"trace[1562543281] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"191.597554ms","start":"2026-04-13T21:00:04.15299Z","end":"2026-04-13T21:00:04.344588Z","steps":["trace[1562543281] 'process raft request' (duration: 191.41911ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:18.16018Z","caller":"traceutil/trace.go:171","msg":"trace[1310977976] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"100.468757ms","start":"2026-04-13T21:00:18.059687Z","end":"2026-04-13T21:00:18.160156Z","steps":["trace[1310977976] 'process raft request' (duration: 100.241071ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:18.168452Z","caller":"traceutil/trace.go:171","msg":"trace[1965322633] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"100.125557ms","start":"2026-04-13T21:00:18.068309Z","end":"2026-04-13T21:00:18.168435Z","steps":["trace[1965322633] 'process raft request' (duration: 99.710787ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:18.168647Z","caller":"traceutil/trace.go:171","msg":"trace[2063871490] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"107.632877ms","start":"2026-04-13T21:00:18.060995Z","end":"2026-04-13T21:00:18.168628Z","steps":["trace[2063871490] 'process raft request' (duration: 106.93958ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:18.16874Z","caller":"traceutil/trace.go:171","msg":"trace[2067977486] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"107.815622ms","start":"2026-04-13T21:00:18.060916Z","end":"2026-04-13T21:00:18.168732Z","steps":["trace[2067977486] 'process raft request' (duration: 106.919219ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:18.521245Z","caller":"traceutil/trace.go:171","msg":"trace[497934052] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"241.172572ms","start":"2026-04-13T21:00:18.280048Z","end":"2026-04-13T21:00:18.52122Z","steps":["trace[497934052] 'process raft request' (duration: 241.006387ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:18.521298Z","caller":"traceutil/trace.go:171","msg":"trace[1755151398] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"269.183602ms","start":"2026-04-13T21:00:18.252075Z","end":"2026-04-13T21:00:18.521259Z","steps":["trace[1755151398] 'process raft request' (duration: 240.750151ms)","trace[1755151398] 'compare' (duration: 28.034501ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:00:18.798985Z","caller":"traceutil/trace.go:171","msg":"trace[1887959604] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"222.247902ms","start":"2026-04-13T21:00:18.576706Z","end":"2026-04-13T21:00:18.798954Z","steps":["trace[1887959604] 'process raft request' (duration: 212.494885ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:18.799206Z","caller":"traceutil/trace.go:171","msg":"trace[1940133620] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"222.450858ms","start":"2026-04-13T21:00:18.576712Z","end":"2026-04-13T21:00:18.799163Z","steps":["trace[1940133620] 'process raft request' (duration: 222.177221ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:18.799288Z","caller":"traceutil/trace.go:171","msg":"trace[1771634498] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"219.525014ms","start":"2026-04-13T21:00:18.579754Z","end":"2026-04-13T21:00:18.799279Z","steps":["trace[1771634498] 'process raft request' (duration: 219.417611ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:18.799276Z","caller":"traceutil/trace.go:171","msg":"trace[519585179] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"221.689588ms","start":"2026-04-13T21:00:18.577565Z","end":"2026-04-13T21:00:18.799255Z","steps":["trace[519585179] 'process raft request' (duration: 221.528604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:25.905294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.209801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:00:25.905411Z","caller":"traceutil/trace.go:171","msg":"trace[305666283] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"395.570793ms","start":"2026-04-13T21:00:25.509823Z","end":"2026-04-13T21:00:25.905394Z","steps":["trace[305666283] 'read index received' (duration: 270.963672ms)","trace[305666283] 'applied index is now lower than readState.Index' (duration: 124.60528ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:00:25.905516Z","caller":"traceutil/trace.go:171","msg":"trace[1830150458] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"467.646811ms","start":"2026-04-13T21:00:25.437831Z","end":"2026-04-13T21:00:25.905477Z","steps":["trace[1830150458] 'process raft request' (duration: 343.008309ms)","trace[1830150458] 'compare' (duration: 123.897403ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:00:25.905675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.841339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8z7zx\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-13T21:00:25.905687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:00:25.437806Z","time spent":"467.813195ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:00:25.905711Z","caller":"traceutil/trace.go:171","msg":"trace[2033891359] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8z7zx; range_end:; response_count:1; response_revision:580; }","duration":"395.920701ms","start":"2026-04-13T21:00:25.50978Z","end":"2026-04-13T21:00:25.905701Z","steps":["trace[2033891359] 'agreement among raft nodes before linearized reading' (duration: 395.701016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:25.905748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:00:25.509754Z","time spent":"395.985153ms","remote":"127.0.0.1:41360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-8z7zx\" "} {"level":"warn","ts":"2026-04-13T21:00:25.905801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.249935ms","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-13T21:00:25.905899Z","caller":"traceutil/trace.go:171","msg":"trace[1502412341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"285.404199ms","start":"2026-04-13T21:00:25.620469Z","end":"2026-04-13T21:00:25.905873Z","steps":["trace[1502412341] 'agreement among raft nodes before linearized reading' (duration: 285.07137ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:26.146536Z","caller":"traceutil/trace.go:171","msg":"trace[2046677185] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"235.232907ms","start":"2026-04-13T21:00:25.911275Z","end":"2026-04-13T21:00:26.146508Z","steps":["trace[2046677185] 'process raft request' (duration: 235.087313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:26.598568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.337675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:00:26.598855Z","caller":"traceutil/trace.go:171","msg":"trace[1656887241] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"687.395927ms","start":"2026-04-13T21:00:25.911412Z","end":"2026-04-13T21:00:26.598808Z","steps":["trace[1656887241] 'process raft request' (duration: 586.568299ms)","trace[1656887241] 'compare' (duration: 100.084449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:00:26.598964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:00:25.911368Z","time spent":"687.54676ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:00:26.598974Z","caller":"traceutil/trace.go:171","msg":"trace[1006281986] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"682.301247ms","start":"2026-04-13T21:00:25.916657Z","end":"2026-04-13T21:00:26.598958Z","steps":["trace[1006281986] 'read index received' (duration: 229.761217ms)","trace[1006281986] 'applied index is now lower than readState.Index' (duration: 452.53744ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:00:26.599156Z","caller":"traceutil/trace.go:171","msg":"trace[77110506] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"678.389258ms","start":"2026-04-13T21:00:25.920742Z","end":"2026-04-13T21:00:26.599132Z","steps":["trace[77110506] 'process raft request' (duration: 677.944467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:26.599204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.531083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:00:26.599268Z","caller":"traceutil/trace.go:171","msg":"trace[491161476] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:583; }","duration":"682.626865ms","start":"2026-04-13T21:00:25.91662Z","end":"2026-04-13T21:00:26.599247Z","steps":["trace[491161476] 'agreement among raft nodes before linearized reading' (duration: 682.519932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:26.599279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:00:25.920733Z","time spent":"678.48161ms","remote":"127.0.0.1:41360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:00:26.599302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:00:25.91661Z","time spent":"682.683577ms","remote":"127.0.0.1:41394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-13T21:00:26.701895Z","caller":"traceutil/trace.go:171","msg":"trace[1387239280] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"102.859829ms","start":"2026-04-13T21:00:26.599006Z","end":"2026-04-13T21:00:26.701866Z","steps":["trace[1387239280] 'read index received' (duration: 39.121492ms)","trace[1387239280] 'applied index is now lower than readState.Index' (duration: 63.735867ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:00:26.702137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.063213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T21:00:26.702211Z","caller":"traceutil/trace.go:171","msg":"trace[917263495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"768.185885ms","start":"2026-04-13T21:00:25.934009Z","end":"2026-04-13T21:00:26.702195Z","steps":["trace[917263495] 'agreement among raft nodes before linearized reading' (duration: 767.96423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:26.702259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:00:25.933993Z","time spent":"768.251878ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T21:00:27.862597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058204885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:00:27.862693Z","caller":"traceutil/trace.go:171","msg":"trace[1841169307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"1.05840218s","start":"2026-04-13T21:00:26.804274Z","end":"2026-04-13T21:00:27.862676Z","steps":["trace[1841169307] 'range keys from in-memory index tree' (duration: 1.058058521s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:27.862738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:00:26.804252Z","time spent":"1.058476332s","remote":"127.0.0.1:41210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T21:00:27.894402Z","caller":"traceutil/trace.go:171","msg":"trace[83011234] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"499.200754ms","start":"2026-04-13T21:00:27.395176Z","end":"2026-04-13T21:00:27.894377Z","steps":["trace[83011234] 'read index received' (duration: 498.417484ms)","trace[83011234] 'applied index is now lower than readState.Index' (duration: 782.34µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:00:27.894627Z","caller":"traceutil/trace.go:171","msg":"trace[1546577832] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"906.117277ms","start":"2026-04-13T21:00:26.988498Z","end":"2026-04-13T21:00:27.894616Z","steps":["trace[1546577832] 'process raft request' (duration: 905.359698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:27.894748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:00:26.988468Z","time spent":"906.19788ms","remote":"127.0.0.1:41300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:00:27.895151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.959933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T21:00:27.895246Z","caller":"traceutil/trace.go:171","msg":"trace[2141846303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"500.096947ms","start":"2026-04-13T21:00:27.395127Z","end":"2026-04-13T21:00:27.895223Z","steps":["trace[2141846303] 'agreement among raft nodes before linearized reading' (duration: 499.715367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:27.895309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:00:27.395106Z","time spent":"500.18766ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T21:00:27.89567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.610155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:00:27.895725Z","caller":"traceutil/trace.go:171","msg":"trace[937862640] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:585; }","duration":"268.694406ms","start":"2026-04-13T21:00:27.627018Z","end":"2026-04-13T21:00:27.895712Z","steps":["trace[937862640] 'agreement among raft nodes before linearized reading' (duration: 268.613614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:27.895916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.283424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:00:27.895944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.132077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:00:27.896054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.701429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:00:27.896097Z","caller":"traceutil/trace.go:171","msg":"trace[349272270] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:585; }","duration":"127.783722ms","start":"2026-04-13T21:00:27.768302Z","end":"2026-04-13T21:00:27.896086Z","steps":["trace[349272270] 'agreement among raft nodes before linearized reading' (duration: 127.70214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:27.896119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.50999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:00:27.895958Z","caller":"traceutil/trace.go:171","msg":"trace[1606528352] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:585; }","duration":"128.335065ms","start":"2026-04-13T21:00:27.76761Z","end":"2026-04-13T21:00:27.895946Z","steps":["trace[1606528352] 'agreement among raft nodes before linearized reading' (duration: 128.277064ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:27.896208Z","caller":"traceutil/trace.go:171","msg":"trace[1060398267] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:585; }","duration":"128.660304ms","start":"2026-04-13T21:00:27.767534Z","end":"2026-04-13T21:00:27.896194Z","steps":["trace[1060398267] 'agreement among raft nodes before linearized reading' (duration: 128.463519ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:27.896098Z","caller":"traceutil/trace.go:171","msg":"trace[1325341494] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:585; }","duration":"105.357853ms","start":"2026-04-13T21:00:27.790725Z","end":"2026-04-13T21:00:27.896083Z","steps":["trace[1325341494] 'agreement among raft nodes before linearized reading' (duration: 105.118497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:27.896277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.24434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:00:27.895993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.81761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:00:27.896342Z","caller":"traceutil/trace.go:171","msg":"trace[174205990] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:585; }","duration":"269.20771ms","start":"2026-04-13T21:00:27.627122Z","end":"2026-04-13T21:00:27.896329Z","steps":["trace[174205990] 'agreement among raft nodes before linearized reading' (duration: 268.844331ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:27.896342Z","caller":"traceutil/trace.go:171","msg":"trace[196503413] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:585; }","duration":"105.359653ms","start":"2026-04-13T21:00:27.790968Z","end":"2026-04-13T21:00:27.896327Z","steps":["trace[196503413] 'agreement among raft nodes before linearized reading' (duration: 105.24144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:27.896476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.750722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:00:27.896581Z","caller":"traceutil/trace.go:171","msg":"trace[1631173424] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:585; }","duration":"105.802044ms","start":"2026-04-13T21:00:27.790713Z","end":"2026-04-13T21:00:27.896515Z","steps":["trace[1631173424] 'agreement among raft nodes before linearized reading' (duration: 105.26678ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:28.069236Z","caller":"traceutil/trace.go:171","msg":"trace[236262613] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"117.080201ms","start":"2026-04-13T21:00:27.952134Z","end":"2026-04-13T21:00:28.069214Z","steps":["trace[236262613] 'process raft request' (duration: 117.03003ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:28.069367Z","caller":"traceutil/trace.go:171","msg":"trace[184386309] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"165.511809ms","start":"2026-04-13T21:00:27.903815Z","end":"2026-04-13T21:00:28.069327Z","steps":["trace[184386309] 'process raft request' (duration: 68.322113ms)","trace[184386309] 'compare' (duration: 96.855167ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:00:28.069931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.59806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:00:28.069993Z","caller":"traceutil/trace.go:171","msg":"trace[1040071617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"163.706563ms","start":"2026-04-13T21:00:27.906274Z","end":"2026-04-13T21:00:28.06998Z","steps":["trace[1040071617] 'agreement among raft nodes before linearized reading' (duration: 163.5833ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:28.069685Z","caller":"traceutil/trace.go:171","msg":"trace[1984135303] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"163.351754ms","start":"2026-04-13T21:00:27.906311Z","end":"2026-04-13T21:00:28.069662Z","steps":["trace[1984135303] 'read index received' (duration: 65.838341ms)","trace[1984135303] 'applied index is now lower than readState.Index' (duration: 97.511023ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:00:28.0718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.593012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:00:28.072086Z","caller":"traceutil/trace.go:171","msg":"trace[572596605] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:587; }","duration":"139.880749ms","start":"2026-04-13T21:00:27.93217Z","end":"2026-04-13T21:00:28.072051Z","steps":["trace[572596605] 'agreement among raft nodes before linearized reading' (duration: 139.585821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:28.072229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.363201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:00:28.072429Z","caller":"traceutil/trace.go:171","msg":"trace[179547246] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:587; }","duration":"140.541096ms","start":"2026-04-13T21:00:27.931829Z","end":"2026-04-13T21:00:28.07237Z","steps":["trace[179547246] 'agreement among raft nodes before linearized reading' (duration: 140.368732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:28.071982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.066614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:00:28.072585Z","caller":"traceutil/trace.go:171","msg":"trace[159724723] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:587; }","duration":"140.666809ms","start":"2026-04-13T21:00:27.931903Z","end":"2026-04-13T21:00:28.07257Z","steps":["trace[159724723] 'agreement among raft nodes before linearized reading' (duration: 140.050423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:30.011249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.213757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:00:30.011329Z","caller":"traceutil/trace.go:171","msg":"trace[295178081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"205.34058ms","start":"2026-04-13T21:00:29.805971Z","end":"2026-04-13T21:00:30.011311Z","steps":["trace[295178081] 'range keys from in-memory index tree' (duration: 205.130585ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:44.097989Z","caller":"traceutil/trace.go:171","msg":"trace[1296367725] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"487.565092ms","start":"2026-04-13T21:00:43.610401Z","end":"2026-04-13T21:00:44.097967Z","steps":["trace[1296367725] 'process raft request' (duration: 487.325697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:00:44.098154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.454389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:00:44.098169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:00:43.610372Z","time spent":"487.692234ms","remote":"127.0.0.1:41360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:00:44.098237Z","caller":"traceutil/trace.go:171","msg":"trace[834398827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"292.565272ms","start":"2026-04-13T21:00:43.805639Z","end":"2026-04-13T21:00:44.098205Z","steps":["trace[834398827] 'agreement among raft nodes before linearized reading' (duration: 292.437079ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:00:44.09791Z","caller":"traceutil/trace.go:171","msg":"trace[223814018] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:774; }","duration":"292.195344ms","start":"2026-04-13T21:00:43.805686Z","end":"2026-04-13T21:00:44.097881Z","steps":["trace[223814018] 'read index received' (duration: 291.941919ms)","trace[223814018] 'applied index is now lower than readState.Index' (duration: 252.585µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:01:13.541361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.142367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:01:13.541474Z","caller":"traceutil/trace.go:171","msg":"trace[538185637] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1150; }","duration":"433.26758ms","start":"2026-04-13T21:01:13.108169Z","end":"2026-04-13T21:01:13.541437Z","steps":["trace[538185637] 'count revisions from in-memory index tree' (duration: 433.039316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:01:13.541524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:01:13.108116Z","time spent":"433.396264ms","remote":"127.0.0.1:41654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":31,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:01:13.541638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.863634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T21:01:13.541711Z","caller":"traceutil/trace.go:171","msg":"trace[718928408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1150; }","duration":"555.977487ms","start":"2026-04-13T21:01:12.985715Z","end":"2026-04-13T21:01:13.541693Z","steps":["trace[718928408] 'range keys from in-memory index tree' (duration: 555.622009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:01:13.541764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:01:12.9857Z","time spent":"556.054828ms","remote":"127.0.0.1:41412","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-13T21:01:34.268161Z","caller":"traceutil/trace.go:171","msg":"trace[83937432] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"121.630349ms","start":"2026-04-13T21:01:34.146504Z","end":"2026-04-13T21:01:34.268134Z","steps":["trace[83937432] 'process raft request' (duration: 121.472665ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:01:58.816071Z","caller":"traceutil/trace.go:171","msg":"trace[448603852] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1877; }","duration":"229.148463ms","start":"2026-04-13T21:01:58.586898Z","end":"2026-04-13T21:01:58.816046Z","steps":["trace[448603852] 'read index received' (duration: 228.902177ms)","trace[448603852] 'applied index is now lower than readState.Index' (duration: 245.316µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:01:58.816501Z","caller":"traceutil/trace.go:171","msg":"trace[1244401844] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"274.688181ms","start":"2026-04-13T21:01:58.54176Z","end":"2026-04-13T21:01:58.816448Z","steps":["trace[1244401844] 'process raft request' (duration: 274.108787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:01:58.816773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.903967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T21:01:58.816864Z","caller":"traceutil/trace.go:171","msg":"trace[415733491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1806; }","duration":"161.023271ms","start":"2026-04-13T21:01:58.655822Z","end":"2026-04-13T21:01:58.816845Z","steps":["trace[415733491] 'agreement among raft nodes before linearized reading' (duration: 160.767255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:01:58.817335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.417943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-13T21:01:58.817397Z","caller":"traceutil/trace.go:171","msg":"trace[211795679] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1806; }","duration":"230.489915ms","start":"2026-04-13T21:01:58.586891Z","end":"2026-04-13T21:01:58.817381Z","steps":["trace[211795679] 'agreement among raft nodes before linearized reading' (duration: 230.346601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:01:59.384779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.779691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:01:59.385025Z","caller":"traceutil/trace.go:171","msg":"trace[667470655] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1878; }","duration":"466.400966ms","start":"2026-04-13T21:01:58.918606Z","end":"2026-04-13T21:01:59.385007Z","steps":["trace[667470655] 'read index received' (duration: 364.301727ms)","trace[667470655] 'applied index is now lower than readState.Index' (duration: 102.098128ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:01:59.385343Z","caller":"traceutil/trace.go:171","msg":"trace[236537511] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"422.21544ms","start":"2026-04-13T21:01:58.963089Z","end":"2026-04-13T21:01:59.385305Z","steps":["trace[236537511] 'process raft request' (duration: 421.884082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:01:59.385541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:01:58.963067Z","time spent":"422.358053ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:01:59.385445Z","caller":"traceutil/trace.go:171","msg":"trace[895768820] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"562.240074ms","start":"2026-04-13T21:01:58.823159Z","end":"2026-04-13T21:01:59.385399Z","steps":["trace[895768820] 'process raft request' (duration: 561.719401ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:01:59.385495Z","caller":"traceutil/trace.go:171","msg":"trace[1058196358] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"562.574872ms","start":"2026-04-13T21:01:58.822859Z","end":"2026-04-13T21:01:59.385434Z","steps":["trace[1058196358] 'process raft request' (duration: 460.050543ms)","trace[1058196358] 'compare' (duration: 101.254169ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:01:59.385786Z","caller":"traceutil/trace.go:171","msg":"trace[1212475435] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"558.745331ms","start":"2026-04-13T21:01:58.82703Z","end":"2026-04-13T21:01:59.385775Z","steps":["trace[1212475435] 'process raft request' (duration: 557.910881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:01:59.385842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:01:58.827006Z","time spent":"558.806513ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:01:59.385949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:01:58.822835Z","time spent":"562.998441ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:01:59.385962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:01:58.823149Z","time spent":"562.50924ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:01:59.386348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.753216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca1feecd-fbe0-4ff4-958d-ed7975c472a5\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-13T21:01:59.386444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.111037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca1feecd-fbe0-4ff4-958d-ed7975c472a5\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-13T21:01:59.386423Z","caller":"traceutil/trace.go:171","msg":"trace[1530369551] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ca1feecd-fbe0-4ff4-958d-ed7975c472a5; range_end:; response_count:1; response_revision:1810; }","duration":"404.851859ms","start":"2026-04-13T21:01:58.981551Z","end":"2026-04-13T21:01:59.386403Z","steps":["trace[1530369551] 'agreement among raft nodes before linearized reading' (duration: 404.609503ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:01:59.386486Z","caller":"traceutil/trace.go:171","msg":"trace[1406331696] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ca1feecd-fbe0-4ff4-958d-ed7975c472a5; range_end:; response_count:1; response_revision:1810; }","duration":"251.171029ms","start":"2026-04-13T21:01:59.135304Z","end":"2026-04-13T21:01:59.386475Z","steps":["trace[1406331696] 'agreement among raft nodes before linearized reading' (duration: 250.993255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:01:59.386503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:01:58.981539Z","time spent":"404.950371ms","remote":"127.0.0.1:41360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca1feecd-fbe0-4ff4-958d-ed7975c472a5\" "} {"level":"warn","ts":"2026-04-13T21:01:59.386564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.576387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T21:01:59.386635Z","caller":"traceutil/trace.go:171","msg":"trace[76538195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1810; }","duration":"275.6846ms","start":"2026-04-13T21:01:59.110933Z","end":"2026-04-13T21:01:59.386618Z","steps":["trace[76538195] 'agreement among raft nodes before linearized reading' (duration: 275.481645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:01:59.386827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.836399ms","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-13T21:01:59.386888Z","caller":"traceutil/trace.go:171","msg":"trace[371109339] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1810; }","duration":"468.316481ms","start":"2026-04-13T21:01:58.918555Z","end":"2026-04-13T21:01:59.386872Z","steps":["trace[371109339] 'agreement among raft nodes before linearized reading' (duration: 466.941288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:01:59.387017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:01:58.918534Z","time spent":"468.467895ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T21:01:59.55151Z","caller":"traceutil/trace.go:171","msg":"trace[1342265845] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"158.924774ms","start":"2026-04-13T21:01:59.392551Z","end":"2026-04-13T21:01:59.551476Z","steps":["trace[1342265845] 'process raft request' (duration: 96.365233ms)","trace[1342265845] 'compare' (duration: 62.432538ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:01:59.64756Z","caller":"traceutil/trace.go:171","msg":"trace[588537369] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"254.660202ms","start":"2026-04-13T21:01:59.392858Z","end":"2026-04-13T21:01:59.647518Z","steps":["trace[588537369] 'process raft request' (duration: 253.972176ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:01:59.647935Z","caller":"traceutil/trace.go:171","msg":"trace[1114841073] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"254.13356ms","start":"2026-04-13T21:01:59.393788Z","end":"2026-04-13T21:01:59.647922Z","steps":["trace[1114841073] 'process raft request' (duration: 253.367322ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:01:59.648023Z","caller":"traceutil/trace.go:171","msg":"trace[33141211] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1882; }","duration":"208.97468ms","start":"2026-04-13T21:01:59.43902Z","end":"2026-04-13T21:01:59.647995Z","steps":["trace[33141211] 'read index received' (duration: 49.889511ms)","trace[33141211] 'applied index is now lower than readState.Index' (duration: 159.082249ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:01:59.646975Z","caller":"traceutil/trace.go:171","msg":"trace[1375423094] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"254.119839ms","start":"2026-04-13T21:01:59.39279Z","end":"2026-04-13T21:01:59.64691Z","steps":["trace[1375423094] 'process raft request' (duration: 253.858383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:01:59.648315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.279357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:01:59.649152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.74535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-13T21:01:59.649253Z","caller":"traceutil/trace.go:171","msg":"trace[1109455236] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1814; }","duration":"132.700813ms","start":"2026-04-13T21:01:59.516525Z","end":"2026-04-13T21:01:59.649226Z","steps":["trace[1109455236] 'agreement among raft nodes before linearized reading' (duration: 131.624147ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:01:59.648365Z","caller":"traceutil/trace.go:171","msg":"trace[853649314] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1814; }","duration":"209.39483ms","start":"2026-04-13T21:01:59.438959Z","end":"2026-04-13T21:01:59.648353Z","steps":["trace[853649314] 'agreement among raft nodes before linearized reading' (duration: 209.188105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:02:00.202335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.950163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:02:00.20242Z","caller":"traceutil/trace.go:171","msg":"trace[1409177436] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1814; }","duration":"513.099487ms","start":"2026-04-13T21:01:59.689299Z","end":"2026-04-13T21:02:00.202398Z","steps":["trace[1409177436] 'count revisions from in-memory index tree' (duration: 512.857351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:02:00.202502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:01:59.689274Z","time spent":"513.21115ms","remote":"127.0.0.1:41606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:02:00.202488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.23098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:02:00.202587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.815886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-13T21:02:00.202586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.551442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T21:02:00.20259Z","caller":"traceutil/trace.go:171","msg":"trace[1380358442] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1814; }","duration":"163.381292ms","start":"2026-04-13T21:02:00.039179Z","end":"2026-04-13T21:02:00.20256Z","steps":["trace[1380358442] 'count revisions from in-memory index tree' (duration: 163.132227ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:02:00.202642Z","caller":"traceutil/trace.go:171","msg":"trace[504770418] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1814; }","duration":"378.881278ms","start":"2026-04-13T21:01:59.823729Z","end":"2026-04-13T21:02:00.202611Z","steps":["trace[504770418] 'range keys from in-memory index tree' (duration: 378.670623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:02:00.202711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:01:59.82371Z","time spent":"378.99389ms","remote":"127.0.0.1:52956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-13T21:02:00.202639Z","caller":"traceutil/trace.go:171","msg":"trace[1586696517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1814; }","duration":"403.638594ms","start":"2026-04-13T21:01:59.798987Z","end":"2026-04-13T21:02:00.202625Z","steps":["trace[1586696517] 'range keys from in-memory index tree' (duration: 403.380518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:02:00.202778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:01:59.798966Z","time spent":"403.794878ms","remote":"127.0.0.1:41412","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-13T21:02:00.223231Z","caller":"traceutil/trace.go:171","msg":"trace[1606759940] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"178.061731ms","start":"2026-04-13T21:02:00.045109Z","end":"2026-04-13T21:02:00.223171Z","steps":["trace[1606759940] 'process raft request' (duration: 177.873426ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:02:12.845876Z","caller":"traceutil/trace.go:171","msg":"trace[1309113112] linearizableReadLoop","detail":"{readStateIndex:1999; appliedIndex:1998; }","duration":"157.566376ms","start":"2026-04-13T21:02:12.688285Z","end":"2026-04-13T21:02:12.845851Z","steps":["trace[1309113112] 'read index received' (duration: 157.268709ms)","trace[1309113112] 'applied index is now lower than readState.Index' (duration: 296.477µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:02:12.846114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.787791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:02:12.846148Z","caller":"traceutil/trace.go:171","msg":"trace[2083396563] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1924; }","duration":"157.901714ms","start":"2026-04-13T21:02:12.688236Z","end":"2026-04-13T21:02:12.846138Z","steps":["trace[2083396563] 'agreement among raft nodes before linearized reading' (duration: 157.7279ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:02:12.846412Z","caller":"traceutil/trace.go:171","msg":"trace[2025832449] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"319.373815ms","start":"2026-04-13T21:02:12.527026Z","end":"2026-04-13T21:02:12.8464Z","steps":["trace[2025832449] 'process raft request' (duration: 318.630418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:02:12.84649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:02:12.527006Z","time spent":"319.430647ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:02:34.010012Z","caller":"traceutil/trace.go:171","msg":"trace[847443635] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"275.474515ms","start":"2026-04-13T21:02:33.73452Z","end":"2026-04-13T21:02:34.009995Z","steps":["trace[847443635] 'process raft request' (duration: 270.417502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:02:34.010406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.9747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:02:34.010601Z","caller":"traceutil/trace.go:171","msg":"trace[700905412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2055; }","duration":"203.134755ms","start":"2026-04-13T21:02:33.807354Z","end":"2026-04-13T21:02:34.010489Z","steps":["trace[700905412] 'agreement among raft nodes before linearized reading' (duration: 202.988062ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:02:34.010805Z","caller":"traceutil/trace.go:171","msg":"trace[601822187] linearizableReadLoop","detail":"{readStateIndex:2134; appliedIndex:2133; }","duration":"202.51243ms","start":"2026-04-13T21:02:33.80742Z","end":"2026-04-13T21:02:34.009932Z","steps":["trace[601822187] 'read index received' (duration: 197.419516ms)","trace[601822187] 'applied index is now lower than readState.Index' (duration: 5.091854ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:02:34.011181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.583371ms","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-13T21:02:34.011233Z","caller":"traceutil/trace.go:171","msg":"trace[662170702] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2055; }","duration":"190.676593ms","start":"2026-04-13T21:02:33.820543Z","end":"2026-04-13T21:02:34.011219Z","steps":["trace[662170702] 'agreement among raft nodes before linearized reading' (duration: 190.529999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:02:34.011666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.457606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-13T21:02:34.011829Z","caller":"traceutil/trace.go:171","msg":"trace[681991782] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2055; }","duration":"192.673991ms","start":"2026-04-13T21:02:33.819142Z","end":"2026-04-13T21:02:34.011816Z","steps":["trace[681991782] 'agreement among raft nodes before linearized reading' (duration: 191.855731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:02:34.012169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.009003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:02:34.012223Z","caller":"traceutil/trace.go:171","msg":"trace[1208715261] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2055; }","duration":"146.200638ms","start":"2026-04-13T21:02:33.86601Z","end":"2026-04-13T21:02:34.012211Z","steps":["trace[1208715261] 'agreement among raft nodes before linearized reading' (duration: 145.006859ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:02:34.504758Z","caller":"traceutil/trace.go:171","msg":"trace[61125296] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"194.328043ms","start":"2026-04-13T21:02:34.310397Z","end":"2026-04-13T21:02:34.504725Z","steps":["trace[61125296] 'process raft request' (duration: 194.099878ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:02:35.07675Z","caller":"traceutil/trace.go:171","msg":"trace[1537206680] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"144.434027ms","start":"2026-04-13T21:02:34.932285Z","end":"2026-04-13T21:02:35.076719Z","steps":["trace[1537206680] 'process raft request' (duration: 144.267803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:02:35.84304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.155599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:02:35.843316Z","caller":"traceutil/trace.go:171","msg":"trace[106665689] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"149.813738ms","start":"2026-04-13T21:02:35.693458Z","end":"2026-04-13T21:02:35.843272Z","steps":["trace[106665689] 'process raft request' (duration: 41.008713ms)","trace[106665689] 'compare' (duration: 107.505303ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:02:35.843565Z","caller":"traceutil/trace.go:171","msg":"trace[736661538] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"147.824769ms","start":"2026-04-13T21:02:35.695708Z","end":"2026-04-13T21:02:35.843533Z","steps":["trace[736661538] 'process raft request' (duration: 147.509572ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:02:35.843716Z","caller":"traceutil/trace.go:171","msg":"trace[676707241] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"149.999012ms","start":"2026-04-13T21:02:35.693704Z","end":"2026-04-13T21:02:35.843703Z","steps":["trace[676707241] 'process raft request' (duration: 149.452209ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:02:36.266924Z","caller":"traceutil/trace.go:171","msg":"trace[2039241391] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"188.63826ms","start":"2026-04-13T21:02:36.078244Z","end":"2026-04-13T21:02:36.266882Z","steps":["trace[2039241391] 'process raft request' (duration: 188.450286ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:02:36.2953Z","caller":"traceutil/trace.go:171","msg":"trace[2049134532] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"216.69315ms","start":"2026-04-13T21:02:36.078572Z","end":"2026-04-13T21:02:36.295265Z","steps":["trace[2049134532] 'process raft request' (duration: 200.945709ms)","trace[2049134532] 'compare' (duration: 15.601518ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:02:36.295386Z","caller":"traceutil/trace.go:171","msg":"trace[610237222] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"216.70661ms","start":"2026-04-13T21:02:36.078636Z","end":"2026-04-13T21:02:36.295343Z","steps":["trace[610237222] 'process raft request' (duration: 216.559966ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:03:04.611146Z","caller":"traceutil/trace.go:171","msg":"trace[1209943092] linearizableReadLoop","detail":"{readStateIndex:2303; appliedIndex:2302; }","duration":"109.273933ms","start":"2026-04-13T21:03:04.501837Z","end":"2026-04-13T21:03:04.611111Z","steps":["trace[1209943092] 'read index received' (duration: 107.649744ms)","trace[1209943092] 'applied index is now lower than readState.Index' (duration: 1.622729ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:03:04.611266Z","caller":"traceutil/trace.go:171","msg":"trace[1086696103] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"126.699969ms","start":"2026-04-13T21:03:04.484533Z","end":"2026-04-13T21:03:04.611233Z","steps":["trace[1086696103] 'process raft request' (duration: 124.988957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:03:04.611735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.865948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-13T21:03:04.611807Z","caller":"traceutil/trace.go:171","msg":"trace[102623472] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2217; }","duration":"109.976971ms","start":"2026-04-13T21:03:04.501812Z","end":"2026-04-13T21:03:04.611789Z","steps":["trace[102623472] 'agreement among raft nodes before linearized reading' (duration: 109.496719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:03:29.598983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.442393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:03:29.599131Z","caller":"traceutil/trace.go:171","msg":"trace[869320227] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2400; }","duration":"122.685459ms","start":"2026-04-13T21:03:29.476421Z","end":"2026-04-13T21:03:29.599106Z","steps":["trace[869320227] 'count revisions from in-memory index tree' (duration: 122.250989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:03:29.599206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.800628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-95xt6\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-04-13T21:03:29.599343Z","caller":"traceutil/trace.go:171","msg":"trace[958832104] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-95xt6; range_end:; response_count:1; response_revision:2400; }","duration":"185.008843ms","start":"2026-04-13T21:03:29.414301Z","end":"2026-04-13T21:03:29.599309Z","steps":["trace[958832104] 'range keys from in-memory index tree' (duration: 184.353316ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:03:38.706521Z","caller":"traceutil/trace.go:171","msg":"trace[1598930827] linearizableReadLoop","detail":"{readStateIndex:2595; appliedIndex:2594; }","duration":"131.139241ms","start":"2026-04-13T21:03:38.575364Z","end":"2026-04-13T21:03:38.706503Z","steps":["trace[1598930827] 'read index received' (duration: 130.994087ms)","trace[1598930827] 'applied index is now lower than readState.Index' (duration: 143.974µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:03:38.706826Z","caller":"traceutil/trace.go:171","msg":"trace[1902377355] transaction","detail":"{read_only:false; response_revision:2501; number_of_response:1; }","duration":"213.476321ms","start":"2026-04-13T21:03:38.493337Z","end":"2026-04-13T21:03:38.706813Z","steps":["trace[1902377355] 'process raft request' (duration: 213.072501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:03:38.706994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.616644ms","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-13T21:03:38.707018Z","caller":"traceutil/trace.go:171","msg":"trace[1463213830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2501; }","duration":"131.681764ms","start":"2026-04-13T21:03:38.575329Z","end":"2026-04-13T21:03:38.707011Z","steps":["trace[1463213830] 'agreement among raft nodes before linearized reading' (duration: 131.586332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:03:38.707104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.290671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-13T21:03:38.707131Z","caller":"traceutil/trace.go:171","msg":"trace[1059920868] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2501; }","duration":"102.339391ms","start":"2026-04-13T21:03:38.604784Z","end":"2026-04-13T21:03:38.707123Z","steps":["trace[1059920868] 'agreement among raft nodes before linearized reading' (duration: 102.2842ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:03:39.775385Z","caller":"traceutil/trace.go:171","msg":"trace[848336199] linearizableReadLoop","detail":"{readStateIndex:2600; appliedIndex:2599; }","duration":"359.79187ms","start":"2026-04-13T21:03:39.415576Z","end":"2026-04-13T21:03:39.775368Z","steps":["trace[848336199] 'read index received' (duration: 359.650316ms)","trace[848336199] 'applied index is now lower than readState.Index' (duration: 140.794µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:03:39.775513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.920413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:03:39.775539Z","caller":"traceutil/trace.go:171","msg":"trace[2061262535] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2506; }","duration":"360.027946ms","start":"2026-04-13T21:03:39.415503Z","end":"2026-04-13T21:03:39.775531Z","steps":["trace[2061262535] 'agreement among raft nodes before linearized reading' (duration: 359.950454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:03:39.775569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:03:39.415468Z","time spent":"360.094338ms","remote":"127.0.0.1:50438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-13T21:03:39.775761Z","caller":"traceutil/trace.go:171","msg":"trace[1120236978] transaction","detail":"{read_only:false; response_revision:2506; number_of_response:1; }","duration":"484.143035ms","start":"2026-04-13T21:03:39.29161Z","end":"2026-04-13T21:03:39.775753Z","steps":["trace[1120236978] 'process raft request' (duration: 483.664774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:03:39.775823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:03:39.29159Z","time spent":"484.193757ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:04:11.304562Z","caller":"traceutil/trace.go:171","msg":"trace[294537968] transaction","detail":"{read_only:false; response_revision:2679; number_of_response:1; }","duration":"369.207128ms","start":"2026-04-13T21:04:10.935335Z","end":"2026-04-13T21:04:11.304543Z","steps":["trace[294537968] 'process raft request' (duration: 289.072395ms)","trace[294537968] 'compare' (duration: 79.908027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:04:11.304687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:04:10.935318Z","time spent":"369.28926ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:04:11.323418Z","caller":"traceutil/trace.go:171","msg":"trace[304569275] linearizableReadLoop","detail":"{readStateIndex:2781; appliedIndex:2779; }","duration":"289.537407ms","start":"2026-04-13T21:04:11.033861Z","end":"2026-04-13T21:04:11.323399Z","steps":["trace[304569275] 'read index received' (duration: 190.513336ms)","trace[304569275] 'applied index is now lower than readState.Index' (duration: 99.023171ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:04:11.323555Z","caller":"traceutil/trace.go:171","msg":"trace[926962160] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"387.912882ms","start":"2026-04-13T21:04:10.935605Z","end":"2026-04-13T21:04:11.323518Z","steps":["trace[926962160] 'process raft request' (duration: 387.698726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:04:11.32366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.786783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T21:04:11.323724Z","caller":"traceutil/trace.go:171","msg":"trace[986406740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2680; }","duration":"289.891156ms","start":"2026-04-13T21:04:11.033822Z","end":"2026-04-13T21:04:11.323713Z","steps":["trace[986406740] 'agreement among raft nodes before linearized reading' (duration: 289.65659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:04:11.323733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:04:10.935595Z","time spent":"388.047245ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:04:11.675453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.71311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-13T21:04:11.675475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.886554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:04:11.675576Z","caller":"traceutil/trace.go:171","msg":"trace[406461836] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2681; }","duration":"235.076648ms","start":"2026-04-13T21:04:11.440481Z","end":"2026-04-13T21:04:11.675557Z","steps":["trace[406461836] 'count revisions from in-memory index tree' (duration: 234.779871ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:04:11.675526Z","caller":"traceutil/trace.go:171","msg":"trace[1542001345] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:2681; }","duration":"152.827463ms","start":"2026-04-13T21:04:11.522682Z","end":"2026-04-13T21:04:11.675509Z","steps":["trace[1542001345] 'range keys from in-memory index tree' (duration: 152.571016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:04:17.008363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.582081ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-13T21:04:40.697079Z","caller":"traceutil/trace.go:171","msg":"trace[380242081] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"107.840335ms","start":"2026-04-13T21:04:40.589215Z","end":"2026-04-13T21:04:40.697055Z","steps":["trace[380242081] 'process raft request' (duration: 107.296512ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:04:40.697278Z","caller":"traceutil/trace.go:171","msg":"trace[1514377916] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"107.156589ms","start":"2026-04-13T21:04:40.590077Z","end":"2026-04-13T21:04:40.697233Z","steps":["trace[1514377916] 'process raft request' (duration: 106.564514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:04:47.888863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.783365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:04:47.889079Z","caller":"traceutil/trace.go:171","msg":"trace[274296669] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2871; }","duration":"164.288468ms","start":"2026-04-13T21:04:47.724766Z","end":"2026-04-13T21:04:47.889055Z","steps":["trace[274296669] 'count revisions from in-memory index tree' (duration: 163.696592ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:04:47.977369Z","caller":"traceutil/trace.go:171","msg":"trace[1780147473] transaction","detail":"{read_only:false; response_revision:2872; number_of_response:1; }","duration":"107.800106ms","start":"2026-04-13T21:04:47.869538Z","end":"2026-04-13T21:04:47.977338Z","steps":["trace[1780147473] 'process raft request' (duration: 107.713184ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:23.181374Z","caller":"traceutil/trace.go:171","msg":"trace[385029280] transaction","detail":"{read_only:false; response_revision:3820; number_of_response:1; }","duration":"110.003742ms","start":"2026-04-13T21:07:23.071345Z","end":"2026-04-13T21:07:23.181348Z","steps":["trace[385029280] 'process raft request' (duration: 109.947441ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:23.181443Z","caller":"traceutil/trace.go:171","msg":"trace[865460939] linearizableReadLoop","detail":"{readStateIndex:3967; appliedIndex:3966; }","duration":"118.876471ms","start":"2026-04-13T21:07:23.062557Z","end":"2026-04-13T21:07:23.181433Z","steps":["trace[865460939] 'read index received' (duration: 84.172772ms)","trace[865460939] 'applied index is now lower than readState.Index' (duration: 34.702489ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:07:23.181543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.965093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/probes.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:07:23.181375Z","caller":"traceutil/trace.go:171","msg":"trace[1169661957] transaction","detail":"{read_only:false; response_revision:3819; number_of_response:1; }","duration":"123.834926ms","start":"2026-04-13T21:07:23.057515Z","end":"2026-04-13T21:07:23.18135Z","steps":["trace[1169661957] 'process raft request' (duration: 89.428174ms)","trace[1169661957] 'compare' (duration: 33.672767ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:23.181582Z","caller":"traceutil/trace.go:171","msg":"trace[1312214861] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/probes.monitoring.coreos.com; range_end:; response_count:0; response_revision:3820; }","duration":"119.020184ms","start":"2026-04-13T21:07:23.062553Z","end":"2026-04-13T21:07:23.181573Z","steps":["trace[1312214861] 'agreement among raft nodes before linearized reading' (duration: 118.915982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:23.699766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.115638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:07:23.700103Z","caller":"traceutil/trace.go:171","msg":"trace[1256943679] transaction","detail":"{read_only:false; response_revision:3825; number_of_response:1; }","duration":"363.492658ms","start":"2026-04-13T21:07:23.336574Z","end":"2026-04-13T21:07:23.700066Z","steps":["trace[1256943679] 'process raft request' (duration: 187.990912ms)","trace[1256943679] 'compare' (duration: 174.668899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:07:23.700198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:23.336541Z","time spent":"363.611951ms","remote":"127.0.0.1:41266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":323727,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:07:23.700994Z","caller":"traceutil/trace.go:171","msg":"trace[1938884906] linearizableReadLoop","detail":"{readStateIndex:3974; appliedIndex:3972; }","duration":"111.29545ms","start":"2026-04-13T21:07:23.589679Z","end":"2026-04-13T21:07:23.700974Z","steps":["trace[1938884906] 'read index received' (duration: 109.636634ms)","trace[1938884906] 'applied index is now lower than readState.Index' (duration: 1.657896ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:23.701128Z","caller":"traceutil/trace.go:171","msg":"trace[219244435] transaction","detail":"{read_only:false; response_revision:3826; number_of_response:1; }","duration":"321.935194ms","start":"2026-04-13T21:07:23.379178Z","end":"2026-04-13T21:07:23.701113Z","steps":["trace[219244435] 'process raft request' (duration: 320.84433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:23.701297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:07:23.379149Z","time spent":"322.046786ms","remote":"127.0.0.1:41266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":25377,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:07:23.701374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.644987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T21:07:23.701574Z","caller":"traceutil/trace.go:171","msg":"trace[2014402516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3826; }","duration":"111.923513ms","start":"2026-04-13T21:07:23.589628Z","end":"2026-04-13T21:07:23.701552Z","steps":["trace[2014402516] 'agreement among raft nodes before linearized reading' (duration: 111.516304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:23.970265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.813078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:07:23.970646Z","caller":"traceutil/trace.go:171","msg":"trace[782895861] transaction","detail":"{read_only:false; response_revision:3829; number_of_response:1; }","duration":"183.603518ms","start":"2026-04-13T21:07:23.786982Z","end":"2026-04-13T21:07:23.970585Z","steps":["trace[782895861] 'process raft request' (duration: 73.404603ms)","trace[782895861] 'compare' (duration: 109.562462ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:07:23.971041Z","caller":"traceutil/trace.go:171","msg":"trace[2138508703] transaction","detail":"{read_only:false; response_revision:3830; number_of_response:1; }","duration":"183.474116ms","start":"2026-04-13T21:07:23.787547Z","end":"2026-04-13T21:07:23.971021Z","steps":["trace[2138508703] 'process raft request' (duration: 182.802271ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:23.972042Z","caller":"traceutil/trace.go:171","msg":"trace[560227757] linearizableReadLoop","detail":"{readStateIndex:3978; appliedIndex:3976; }","duration":"184.158741ms","start":"2026-04-13T21:07:23.787847Z","end":"2026-04-13T21:07:23.972006Z","steps":["trace[560227757] 'read index received' (duration: 72.581976ms)","trace[560227757] 'applied index is now lower than readState.Index' (duration: 111.575355ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:07:23.9723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.432446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheuses.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:07:23.972346Z","caller":"traceutil/trace.go:171","msg":"trace[759189106] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheuses.monitoring.coreos.com; range_end:; response_count:0; response_revision:3831; }","duration":"184.503348ms","start":"2026-04-13T21:07:23.787832Z","end":"2026-04-13T21:07:23.972335Z","steps":["trace[759189106] 'agreement among raft nodes before linearized reading' (duration: 184.362785ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:07:23.972472Z","caller":"traceutil/trace.go:171","msg":"trace[1574269224] transaction","detail":"{read_only:false; response_revision:3831; number_of_response:1; }","duration":"169.309504ms","start":"2026-04-13T21:07:23.80305Z","end":"2026-04-13T21:07:23.972359Z","steps":["trace[1574269224] 'process raft request' (duration: 167.559577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:07:23.972615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.556669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:07:23.972923Z","caller":"traceutil/trace.go:171","msg":"trace[672666246] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3831; }","duration":"130.907417ms","start":"2026-04-13T21:07:23.842002Z","end":"2026-04-13T21:07:23.972909Z","steps":["trace[672666246] 'agreement among raft nodes before linearized reading' (duration: 130.521899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:12.882995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.870726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1433"} {"level":"info","ts":"2026-04-13T21:08:12.883071Z","caller":"traceutil/trace.go:171","msg":"trace[46348737] range","detail":"{range_begin:/registry/clusterrolebindings/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4586; }","duration":"127.021319ms","start":"2026-04-13T21:08:12.756036Z","end":"2026-04-13T21:08:12.883058Z","steps":["trace[46348737] 'range keys from in-memory index tree' (duration: 126.743183ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:14.05711Z","caller":"traceutil/trace.go:171","msg":"trace[1290447076] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4609; }","duration":"106.918786ms","start":"2026-04-13T21:08:13.950172Z","end":"2026-04-13T21:08:14.057091Z","steps":["trace[1290447076] 'process raft request' (duration: 30.671259ms)","trace[1290447076] 'compare' (duration: 75.987172ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:08:15.021398Z","caller":"traceutil/trace.go:171","msg":"trace[950272667] linearizableReadLoop","detail":"{readStateIndex:4818; appliedIndex:4816; }","duration":"123.673218ms","start":"2026-04-13T21:08:14.897698Z","end":"2026-04-13T21:08:15.021371Z","steps":["trace[950272667] 'read index received' (duration: 26.2048ms)","trace[950272667] 'applied index is now lower than readState.Index' (duration: 97.467448ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:08:15.021502Z","caller":"traceutil/trace.go:171","msg":"trace[1581813296] transaction","detail":"{read_only:false; response_revision:4640; number_of_response:1; }","duration":"124.892815ms","start":"2026-04-13T21:08:14.896589Z","end":"2026-04-13T21:08:15.021482Z","steps":["trace[1581813296] 'process raft request' (duration: 124.587838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:15.021694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.964205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-13T21:08:15.021768Z","caller":"traceutil/trace.go:171","msg":"trace[1297395358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4640; }","duration":"124.045267ms","start":"2026-04-13T21:08:14.897706Z","end":"2026-04-13T21:08:15.021751Z","steps":["trace[1297395358] 'agreement among raft nodes before linearized reading' (duration: 123.828592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:15.021741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.029076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T21:08:15.021885Z","caller":"traceutil/trace.go:171","msg":"trace[1014589757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4640; }","duration":"124.194429ms","start":"2026-04-13T21:08:14.897672Z","end":"2026-04-13T21:08:15.021867Z","steps":["trace[1014589757] 'agreement among raft nodes before linearized reading' (duration: 123.841372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:15.023453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.655026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-13T21:08:15.023558Z","caller":"traceutil/trace.go:171","msg":"trace[322283962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4640; }","duration":"122.792708ms","start":"2026-04-13T21:08:14.900746Z","end":"2026-04-13T21:08:15.023539Z","steps":["trace[322283962] 'agreement among raft nodes before linearized reading' (duration: 120.854075ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:15.19605Z","caller":"traceutil/trace.go:171","msg":"trace[1414733839] linearizableReadLoop","detail":"{readStateIndex:4819; appliedIndex:4818; }","duration":"167.219394ms","start":"2026-04-13T21:08:15.028797Z","end":"2026-04-13T21:08:15.196017Z","steps":["trace[1414733839] 'read index received' (duration: 133.697072ms)","trace[1414733839] 'applied index is now lower than readState.Index' (duration: 33.520662ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:08:15.196184Z","caller":"traceutil/trace.go:171","msg":"trace[1791461878] transaction","detail":"{read_only:false; response_revision:4641; number_of_response:1; }","duration":"167.94177ms","start":"2026-04-13T21:08:15.028207Z","end":"2026-04-13T21:08:15.196149Z","steps":["trace[1791461878] 'process raft request' (duration: 134.273384ms)","trace[1791461878] 'compare' (duration: 33.243517ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:08:15.196314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.4883ms","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-13T21:08:15.196376Z","caller":"traceutil/trace.go:171","msg":"trace[1062968266] 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:4641; }","duration":"167.584733ms","start":"2026-04-13T21:08:15.028771Z","end":"2026-04-13T21:08:15.196356Z","steps":["trace[1062968266] 'agreement among raft nodes before linearized reading' (duration: 167.392129ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:15.224905Z","caller":"traceutil/trace.go:171","msg":"trace[1597148547] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"193.588559ms","start":"2026-04-13T21:08:15.031299Z","end":"2026-04-13T21:08:15.224887Z","steps":["trace[1597148547] 'process raft request' (duration: 193.550918ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:15.22509Z","caller":"traceutil/trace.go:171","msg":"trace[1803682992] transaction","detail":"{read_only:false; response_revision:4642; number_of_response:1; }","duration":"195.46837ms","start":"2026-04-13T21:08:15.029602Z","end":"2026-04-13T21:08:15.22507Z","steps":["trace[1803682992] 'process raft request' (duration: 195.114582ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:15.225129Z","caller":"traceutil/trace.go:171","msg":"trace[15991103] transaction","detail":"{read_only:false; response_revision:4643; number_of_response:1; }","duration":"195.45095ms","start":"2026-04-13T21:08:15.029668Z","end":"2026-04-13T21:08:15.225119Z","steps":["trace[15991103] 'process raft request' (duration: 195.136243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:15.225183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.630563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-13T21:08:15.225228Z","caller":"traceutil/trace.go:171","msg":"trace[1739182122] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4644; }","duration":"195.679315ms","start":"2026-04-13T21:08:15.029533Z","end":"2026-04-13T21:08:15.225212Z","steps":["trace[1739182122] 'agreement among raft nodes before linearized reading' (duration: 195.359978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:15.225222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.041751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"warn","ts":"2026-04-13T21:08:15.225094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.040847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-xqwd6-tls\" ","response":"range_response_count:1 size:2130"} {"level":"info","ts":"2026-04-13T21:08:15.225312Z","caller":"traceutil/trace.go:171","msg":"trace[2114021292] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4644; }","duration":"195.125263ms","start":"2026-04-13T21:08:15.030153Z","end":"2026-04-13T21:08:15.225278Z","steps":["trace[2114021292] 'agreement among raft nodes before linearized reading' (duration: 194.942649ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:15.225381Z","caller":"traceutil/trace.go:171","msg":"trace[1278352020] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-xqwd6-tls; range_end:; response_count:1; response_revision:4644; }","duration":"193.308393ms","start":"2026-04-13T21:08:15.032013Z","end":"2026-04-13T21:08:15.225322Z","steps":["trace[1278352020] 'agreement among raft nodes before linearized reading' (duration: 192.885673ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:15.413726Z","caller":"traceutil/trace.go:171","msg":"trace[444952442] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"117.556804ms","start":"2026-04-13T21:08:15.296151Z","end":"2026-04-13T21:08:15.413708Z","steps":["trace[444952442] 'process raft request' (duration: 117.453802ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:15.504624Z","caller":"traceutil/trace.go:171","msg":"trace[82884889] linearizableReadLoop","detail":"{readStateIndex:4831; appliedIndex:4825; }","duration":"200.189525ms","start":"2026-04-13T21:08:15.304415Z","end":"2026-04-13T21:08:15.504604Z","steps":["trace[82884889] 'read index received' (duration: 109.164989ms)","trace[82884889] 'applied index is now lower than readState.Index' (duration: 91.023606ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:08:15.504733Z","caller":"traceutil/trace.go:171","msg":"trace[1146105880] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"206.876773ms","start":"2026-04-13T21:08:15.297847Z","end":"2026-04-13T21:08:15.504724Z","steps":["trace[1146105880] 'process raft request' (duration: 206.572416ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:15.504639Z","caller":"traceutil/trace.go:171","msg":"trace[1191164452] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"207.370813ms","start":"2026-04-13T21:08:15.297234Z","end":"2026-04-13T21:08:15.504604Z","steps":["trace[1191164452] 'process raft request' (duration: 207.119378ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:15.505165Z","caller":"traceutil/trace.go:171","msg":"trace[2089521664] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"195.112353ms","start":"2026-04-13T21:08:15.310043Z","end":"2026-04-13T21:08:15.505155Z","steps":["trace[2089521664] 'process raft request' (duration: 194.497719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:15.505391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.957402ms","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-13T21:08:15.505433Z","caller":"traceutil/trace.go:171","msg":"trace[1430241255] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4654; }","duration":"201.010763ms","start":"2026-04-13T21:08:15.30441Z","end":"2026-04-13T21:08:15.505421Z","steps":["trace[1430241255] 'agreement among raft nodes before linearized reading' (duration: 200.87699ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:15.505543Z","caller":"traceutil/trace.go:171","msg":"trace[454227329] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"208.662732ms","start":"2026-04-13T21:08:15.296749Z","end":"2026-04-13T21:08:15.505412Z","steps":["trace[454227329] 'process raft request' (duration: 201.754909ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:15.504952Z","caller":"traceutil/trace.go:171","msg":"trace[459677739] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"206.622907ms","start":"2026-04-13T21:08:15.298317Z","end":"2026-04-13T21:08:15.50494Z","steps":["trace[459677739] 'process raft request' (duration: 206.135406ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:15.504988Z","caller":"traceutil/trace.go:171","msg":"trace[1379706753] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"205.315688ms","start":"2026-04-13T21:08:15.299663Z","end":"2026-04-13T21:08:15.504978Z","steps":["trace[1379706753] 'process raft request' (duration: 204.835797ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:16.916033Z","caller":"traceutil/trace.go:171","msg":"trace[491137406] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"176.336459ms","start":"2026-04-13T21:08:16.739667Z","end":"2026-04-13T21:08:16.916004Z","steps":["trace[491137406] 'process raft request' (duration: 176.066883ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:16.920998Z","caller":"traceutil/trace.go:171","msg":"trace[320710504] linearizableReadLoop","detail":"{readStateIndex:4860; appliedIndex:4858; }","duration":"116.161315ms","start":"2026-04-13T21:08:16.804817Z","end":"2026-04-13T21:08:16.920979Z","steps":["trace[320710504] 'read index received' (duration: 111.039101ms)","trace[320710504] 'applied index is now lower than readState.Index' (duration: 5.121464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:08:16.921086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.260717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:08:16.921102Z","caller":"traceutil/trace.go:171","msg":"trace[174876388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4679; }","duration":"116.325959ms","start":"2026-04-13T21:08:16.804771Z","end":"2026-04-13T21:08:16.921097Z","steps":["trace[174876388] 'agreement among raft nodes before linearized reading' (duration: 116.278968ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:16.921235Z","caller":"traceutil/trace.go:171","msg":"trace[1664033539] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"177.929715ms","start":"2026-04-13T21:08:16.743212Z","end":"2026-04-13T21:08:16.921142Z","steps":["trace[1664033539] 'process raft request' (duration: 177.661379ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:22.270312Z","caller":"traceutil/trace.go:171","msg":"trace[458074928] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"105.18695ms","start":"2026-04-13T21:08:22.165105Z","end":"2026-04-13T21:08:22.270292Z","steps":["trace[458074928] 'process raft request' (duration: 105.058247ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:22.556653Z","caller":"traceutil/trace.go:171","msg":"trace[1573826088] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"282.659837ms","start":"2026-04-13T21:08:22.273966Z","end":"2026-04-13T21:08:22.556625Z","steps":["trace[1573826088] 'process raft request' (duration: 211.098195ms)","trace[1573826088] 'compare' (duration: 71.339727ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:08:22.556923Z","caller":"traceutil/trace.go:171","msg":"trace[4419592] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"281.846319ms","start":"2026-04-13T21:08:22.275051Z","end":"2026-04-13T21:08:22.556897Z","steps":["trace[4419592] 'process raft request' (duration: 281.524092ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:22.557163Z","caller":"traceutil/trace.go:171","msg":"trace[1958513981] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"281.742767ms","start":"2026-04-13T21:08:22.275381Z","end":"2026-04-13T21:08:22.557124Z","steps":["trace[1958513981] 'process raft request' (duration: 281.4394ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:23.027873Z","caller":"traceutil/trace.go:171","msg":"trace[878359531] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"235.397146ms","start":"2026-04-13T21:08:22.792448Z","end":"2026-04-13T21:08:23.027845Z","steps":["trace[878359531] 'process raft request' (duration: 235.216502ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:23.028084Z","caller":"traceutil/trace.go:171","msg":"trace[1735070982] linearizableReadLoop","detail":"{readStateIndex:4959; appliedIndex:4957; }","duration":"221.65536ms","start":"2026-04-13T21:08:22.806415Z","end":"2026-04-13T21:08:23.02807Z","steps":["trace[1735070982] 'read index received' (duration: 68.870681ms)","trace[1735070982] 'applied index is now lower than readState.Index' (duration: 152.783179ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:08:23.028172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.748802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:08:23.028208Z","caller":"traceutil/trace.go:171","msg":"trace[1644992354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4776; }","duration":"221.828964ms","start":"2026-04-13T21:08:22.80637Z","end":"2026-04-13T21:08:23.028199Z","steps":["trace[1644992354] 'agreement among raft nodes before linearized reading' (duration: 221.743082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:23.399368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.757104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:08:23.399787Z","caller":"traceutil/trace.go:171","msg":"trace[1651329312] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"520.456621ms","start":"2026-04-13T21:08:22.879294Z","end":"2026-04-13T21:08:23.399751Z","steps":["trace[1651329312] 'process raft request' (duration: 271.960961ms)","trace[1651329312] 'compare' (duration: 247.316374ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:08:23.400095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:22.879278Z","time spent":"520.639056ms","remote":"127.0.0.1:41288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":936,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:08:23.400514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.195942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:08:23.400796Z","caller":"traceutil/trace.go:171","msg":"trace[1370516932] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"161.552346ms","start":"2026-04-13T21:08:23.238627Z","end":"2026-04-13T21:08:23.400179Z","steps":["trace[1370516932] 'process raft request' (duration: 161.496245ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:23.400887Z","caller":"traceutil/trace.go:171","msg":"trace[53203489] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"439.972001ms","start":"2026-04-13T21:08:22.960224Z","end":"2026-04-13T21:08:23.400196Z","steps":["trace[53203489] 'process raft request' (duration: 439.420309ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:23.400969Z","caller":"traceutil/trace.go:171","msg":"trace[1954135670] linearizableReadLoop","detail":"{readStateIndex:4961; appliedIndex:4959; }","duration":"371.026068ms","start":"2026-04-13T21:08:23.029305Z","end":"2026-04-13T21:08:23.400331Z","steps":["trace[1954135670] 'read index received' (duration: 121.959685ms)","trace[1954135670] 'applied index is now lower than readState.Index' (duration: 249.064373ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:08:23.40099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:22.960196Z","time spent":"440.753309ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:08:23.401076Z","caller":"traceutil/trace.go:171","msg":"trace[1790401342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4779; }","duration":"371.461338ms","start":"2026-04-13T21:08:23.029259Z","end":"2026-04-13T21:08:23.40072Z","steps":["trace[1790401342] 'agreement among raft nodes before linearized reading' (duration: 371.076129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:23.401215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:23.029239Z","time spent":"371.870077ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:08:23.401497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.267808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:08:23.402706Z","caller":"traceutil/trace.go:171","msg":"trace[1301120481] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:4780; }","duration":"361.69098ms","start":"2026-04-13T21:08:23.04091Z","end":"2026-04-13T21:08:23.402601Z","steps":["trace[1301120481] 'agreement among raft nodes before linearized reading' (duration: 360.256078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:23.402857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:23.040893Z","time spent":"361.914494ms","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":8,"response size":29,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:08:23.401748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.740835ms","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-13T21:08:23.403029Z","caller":"traceutil/trace.go:171","msg":"trace[1776085250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4780; }","duration":"174.036943ms","start":"2026-04-13T21:08:23.228947Z","end":"2026-04-13T21:08:23.402984Z","steps":["trace[1776085250] 'agreement among raft nodes before linearized reading' (duration: 172.621652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:23.401898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.948747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:08:23.403203Z","caller":"traceutil/trace.go:171","msg":"trace[1783443410] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4780; }","duration":"319.292037ms","start":"2026-04-13T21:08:23.083894Z","end":"2026-04-13T21:08:23.403186Z","steps":["trace[1783443410] 'agreement among raft nodes before linearized reading' (duration: 317.939867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:23.401945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.148806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63693d7c-cf3c-4940-9dc3-4bf3d16b619b\" ","response":"range_response_count:1 size:3766"} {"level":"warn","ts":"2026-04-13T21:08:23.403361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:23.083873Z","time spent":"319.47201ms","remote":"127.0.0.1:52956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-04-13T21:08:23.403366Z","caller":"traceutil/trace.go:171","msg":"trace[771845807] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-63693d7c-cf3c-4940-9dc3-4bf3d16b619b; range_end:; response_count:1; response_revision:4780; }","duration":"237.601339ms","start":"2026-04-13T21:08:23.165747Z","end":"2026-04-13T21:08:23.403349Z","steps":["trace[771845807] 'agreement among raft nodes before linearized reading' (duration: 236.051704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:32.26969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.116868ms","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-13T21:08:32.269781Z","caller":"traceutil/trace.go:171","msg":"trace[1337535636] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4917; }","duration":"125.252711ms","start":"2026-04-13T21:08:32.144506Z","end":"2026-04-13T21:08:32.269759Z","steps":["trace[1337535636] 'count revisions from in-memory index tree' (duration: 125.015916ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:36.276303Z","caller":"traceutil/trace.go:171","msg":"trace[535473166] linearizableReadLoop","detail":"{readStateIndex:5135; appliedIndex:5134; }","duration":"424.852481ms","start":"2026-04-13T21:08:35.851417Z","end":"2026-04-13T21:08:36.27627Z","steps":["trace[535473166] 'read index received' (duration: 424.451952ms)","trace[535473166] 'applied index is now lower than readState.Index' (duration: 399.139µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:08:36.276343Z","caller":"traceutil/trace.go:171","msg":"trace[694065672] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"439.09618ms","start":"2026-04-13T21:08:35.83719Z","end":"2026-04-13T21:08:36.276286Z","steps":["trace[694065672] 'process raft request' (duration: 438.911706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:36.276594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:35.837164Z","time spent":"439.349055ms","remote":"127.0.0.1:41288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:08:36.276742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.229325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-04-13T21:08:36.276814Z","caller":"traceutil/trace.go:171","msg":"trace[1565300018] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4947; }","duration":"365.345158ms","start":"2026-04-13T21:08:35.911455Z","end":"2026-04-13T21:08:36.2768Z","steps":["trace[1565300018] 'agreement among raft nodes before linearized reading' (duration: 365.080152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:36.276851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:35.911391Z","time spent":"365.45391ms","remote":"127.0.0.1:41360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10414,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-13T21:08:36.276753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.36447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-13T21:08:36.276948Z","caller":"traceutil/trace.go:171","msg":"trace[205150426] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4947; }","duration":"146.596326ms","start":"2026-04-13T21:08:36.130332Z","end":"2026-04-13T21:08:36.276929Z","steps":["trace[205150426] 'agreement among raft nodes before linearized reading' (duration: 146.225127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:36.27696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.489385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"warn","ts":"2026-04-13T21:08:36.276753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.010186ms","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-13T21:08:36.277067Z","caller":"traceutil/trace.go:171","msg":"trace[1940004833] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4947; }","duration":"425.668499ms","start":"2026-04-13T21:08:35.851382Z","end":"2026-04-13T21:08:36.277051Z","steps":["trace[1940004833] 'agreement among raft nodes before linearized reading' (duration: 425.220469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:36.277312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:35.851365Z","time spent":"425.932985ms","remote":"127.0.0.1:52760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-13T21:08:36.277174Z","caller":"traceutil/trace.go:171","msg":"trace[1229157265] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4947; }","duration":"284.372234ms","start":"2026-04-13T21:08:35.992716Z","end":"2026-04-13T21:08:36.277089Z","steps":["trace[1229157265] 'agreement among raft nodes before linearized reading' (duration: 283.902744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:36.793504Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409710465682997,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:08:36.846514Z","caller":"traceutil/trace.go:171","msg":"trace[758727741] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"557.301412ms","start":"2026-04-13T21:08:36.289182Z","end":"2026-04-13T21:08:36.846484Z","steps":["trace[758727741] 'process raft request' (duration: 557.116337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:36.846801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:36.289158Z","time spent":"557.441524ms","remote":"127.0.0.1:41360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:08:37.021238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.598879ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-13T21:08:37.021356Z","caller":"traceutil/trace.go:171","msg":"trace[1244034464] linearizableReadLoop","detail":"{readStateIndex:5137; appliedIndex:5135; }","duration":"728.858497ms","start":"2026-04-13T21:08:36.292475Z","end":"2026-04-13T21:08:37.021333Z","steps":["trace[1244034464] 'read index received' (duration: 553.939456ms)","trace[1244034464] 'applied index is now lower than readState.Index' (duration: 174.917201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:08:37.021602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.097572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-04-13T21:08:37.021705Z","caller":"traceutil/trace.go:171","msg":"trace[704575927] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4948; }","duration":"729.253436ms","start":"2026-04-13T21:08:36.29243Z","end":"2026-04-13T21:08:37.021683Z","steps":["trace[704575927] 'agreement among raft nodes before linearized reading' (duration: 728.957439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:37.02176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:36.292376Z","time spent":"729.369998ms","remote":"127.0.0.1:41362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":1473,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-13T21:08:37.029194Z","caller":"traceutil/trace.go:171","msg":"trace[696663144] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"175.658067ms","start":"2026-04-13T21:08:36.853511Z","end":"2026-04-13T21:08:37.029169Z","steps":["trace[696663144] 'process raft request' (duration: 175.413212ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:37.029516Z","caller":"traceutil/trace.go:171","msg":"trace[491425847] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"175.897562ms","start":"2026-04-13T21:08:36.853582Z","end":"2026-04-13T21:08:37.02948Z","steps":["trace[491425847] 'process raft request' (duration: 175.516614ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:37.029548Z","caller":"traceutil/trace.go:171","msg":"trace[1704790350] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"175.311309ms","start":"2026-04-13T21:08:36.854214Z","end":"2026-04-13T21:08:37.029525Z","steps":["trace[1704790350] 'process raft request' (duration: 175.243348ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:37.029647Z","caller":"traceutil/trace.go:171","msg":"trace[1178834114] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"175.77579ms","start":"2026-04-13T21:08:36.853858Z","end":"2026-04-13T21:08:37.029634Z","steps":["trace[1178834114] 'process raft request' (duration: 175.506134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:37.042334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.012234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-04-13T21:08:37.042362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.293046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T21:08:37.042418Z","caller":"traceutil/trace.go:171","msg":"trace[1856393840] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4953; }","duration":"403.144706ms","start":"2026-04-13T21:08:36.639252Z","end":"2026-04-13T21:08:37.042397Z","steps":["trace[1856393840] 'agreement among raft nodes before linearized reading' (duration: 402.840529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:37.042499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:36.639239Z","time spent":"403.247659ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-13T21:08:37.042587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.046942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18a606ba8185087c\" ","response":"range_response_count:1 size:746"} {"level":"info","ts":"2026-04-13T21:08:37.042662Z","caller":"traceutil/trace.go:171","msg":"trace[377082119] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18a606ba8185087c; range_end:; response_count:1; response_revision:4953; }","duration":"750.141404ms","start":"2026-04-13T21:08:36.292509Z","end":"2026-04-13T21:08:37.042651Z","steps":["trace[377082119] 'agreement among raft nodes before linearized reading' (duration: 749.954179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:37.042708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:36.2925Z","time spent":"750.198115ms","remote":"127.0.0.1:41288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":768,"request content":"key:\"/registry/events/openstack/rabbitmq-keystone.18a606ba8185087c\" "} {"level":"warn","ts":"2026-04-13T21:08:37.042345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.129179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-13T21:08:37.042887Z","caller":"traceutil/trace.go:171","msg":"trace[1561676560] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4953; }","duration":"425.731253ms","start":"2026-04-13T21:08:36.617145Z","end":"2026-04-13T21:08:37.042876Z","steps":["trace[1561676560] 'agreement among raft nodes before linearized reading' (duration: 424.965956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:37.042942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:36.617125Z","time spent":"425.803074ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-13T21:08:37.042716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.469364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-xqwd6\" ","response":"range_response_count:1 size:9728"} {"level":"info","ts":"2026-04-13T21:08:37.042988Z","caller":"traceutil/trace.go:171","msg":"trace[1641014929] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-xqwd6; range_end:; response_count:1; response_revision:4953; }","duration":"192.772121ms","start":"2026-04-13T21:08:36.850201Z","end":"2026-04-13T21:08:37.042973Z","steps":["trace[1641014929] 'agreement among raft nodes before linearized reading' (duration: 192.381352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:37.042434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.210761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-13T21:08:37.043054Z","caller":"traceutil/trace.go:171","msg":"trace[1299239956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4953; }","duration":"425.841184ms","start":"2026-04-13T21:08:36.617205Z","end":"2026-04-13T21:08:37.043047Z","steps":["trace[1299239956] 'agreement among raft nodes before linearized reading' (duration: 425.17712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:37.043077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:36.6172Z","time spent":"425.869856ms","remote":"127.0.0.1:41412","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-13T21:08:37.042438Z","caller":"traceutil/trace.go:171","msg":"trace[365343400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4953; }","duration":"696.419849ms","start":"2026-04-13T21:08:36.346Z","end":"2026-04-13T21:08:37.04242Z","steps":["trace[365343400] 'agreement among raft nodes before linearized reading' (duration: 696.130413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:37.043251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:08:36.34598Z","time spent":"697.262798ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T21:08:37.193981Z","caller":"traceutil/trace.go:171","msg":"trace[1514905825] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"118.116949ms","start":"2026-04-13T21:08:37.07583Z","end":"2026-04-13T21:08:37.193947Z","steps":["trace[1514905825] 'process raft request' (duration: 117.852893ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:37.194358Z","caller":"traceutil/trace.go:171","msg":"trace[83759343] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"118.243662ms","start":"2026-04-13T21:08:37.076056Z","end":"2026-04-13T21:08:37.1943Z","steps":["trace[83759343] 'process raft request' (duration: 117.812762ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:37.194548Z","caller":"traceutil/trace.go:171","msg":"trace[1001436308] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5149; }","duration":"113.194909ms","start":"2026-04-13T21:08:37.081331Z","end":"2026-04-13T21:08:37.194526Z","steps":["trace[1001436308] 'read index received' (duration: 17.947833ms)","trace[1001436308] 'applied index is now lower than readState.Index' (duration: 95.245826ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:08:37.1947Z","caller":"traceutil/trace.go:171","msg":"trace[1916680408] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"110.957788ms","start":"2026-04-13T21:08:37.083731Z","end":"2026-04-13T21:08:37.194689Z","steps":["trace[1916680408] 'process raft request' (duration: 110.614641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:08:37.194863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.522807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-04-13T21:08:37.194931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.578887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-04-13T21:08:37.194935Z","caller":"traceutil/trace.go:171","msg":"trace[1406452079] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4964; }","duration":"101.63211ms","start":"2026-04-13T21:08:37.093289Z","end":"2026-04-13T21:08:37.194921Z","steps":["trace[1406452079] 'agreement among raft nodes before linearized reading' (duration: 101.451695ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:37.194967Z","caller":"traceutil/trace.go:171","msg":"trace[275191488] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4964; }","duration":"113.643078ms","start":"2026-04-13T21:08:37.081313Z","end":"2026-04-13T21:08:37.194956Z","steps":["trace[275191488] 'agreement among raft nodes before linearized reading' (duration: 113.501265ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:37.195368Z","caller":"traceutil/trace.go:171","msg":"trace[769249948] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"115.488289ms","start":"2026-04-13T21:08:37.079865Z","end":"2026-04-13T21:08:37.195353Z","steps":["trace[769249948] 'process raft request' (duration: 114.233491ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:08:37.559914Z","caller":"traceutil/trace.go:171","msg":"trace[949957789] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"108.266538ms","start":"2026-04-13T21:08:37.451619Z","end":"2026-04-13T21:08:37.559885Z","steps":["trace[949957789] 'process raft request' (duration: 53.569461ms)","trace[949957789] 'compare' (duration: 54.226226ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:08:37.560279Z","caller":"traceutil/trace.go:171","msg":"trace[1374128810] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"106.041168ms","start":"2026-04-13T21:08:37.454223Z","end":"2026-04-13T21:08:37.560265Z","steps":["trace[1374128810] 'process raft request' (duration: 105.367403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:09:19.419154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.721663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:09:19.419281Z","caller":"traceutil/trace.go:171","msg":"trace[2017465384] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"128.402482ms","start":"2026-04-13T21:09:19.290859Z","end":"2026-04-13T21:09:19.419262Z","steps":["trace[2017465384] 'process raft request' (duration: 15.486754ms)","trace[2017465384] 'compare' (duration: 112.59106ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:09:19.438014Z","caller":"traceutil/trace.go:171","msg":"trace[229339278] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"146.155136ms","start":"2026-04-13T21:09:19.291835Z","end":"2026-04-13T21:09:19.43799Z","steps":["trace[229339278] 'process raft request' (duration: 145.999953ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:19.43812Z","caller":"traceutil/trace.go:171","msg":"trace[314105763] linearizableReadLoop","detail":"{readStateIndex:5518; appliedIndex:5516; }","duration":"128.520274ms","start":"2026-04-13T21:09:19.309565Z","end":"2026-04-13T21:09:19.438086Z","steps":["trace[314105763] 'read index received' (duration: 128.194337ms)","trace[314105763] 'applied index is now lower than readState.Index' (duration: 324.877µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:09:19.438178Z","caller":"traceutil/trace.go:171","msg":"trace[632230620] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"144.082689ms","start":"2026-04-13T21:09:19.294072Z","end":"2026-04-13T21:09:19.438154Z","steps":["trace[632230620] 'process raft request' (duration: 143.878874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:09:19.438372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.78864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-04-13T21:09:19.438405Z","caller":"traceutil/trace.go:171","msg":"trace[1904741929] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5320; }","duration":"128.857131ms","start":"2026-04-13T21:09:19.309539Z","end":"2026-04-13T21:09:19.438396Z","steps":["trace[1904741929] 'agreement among raft nodes before linearized reading' (duration: 128.671717ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:32.111811Z","caller":"traceutil/trace.go:171","msg":"trace[318470671] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"132.288813ms","start":"2026-04-13T21:09:31.979496Z","end":"2026-04-13T21:09:32.111785Z","steps":["trace[318470671] 'process raft request' (duration: 132.109129ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:09:47.984636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2872} {"level":"info","ts":"2026-04-13T21:09:48.058335Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2872,"took":"71.852766ms","hash":4259844021,"current-db-size-bytes":54607872,"current-db-size":"55 MB","current-db-size-in-use-bytes":29577216,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-13T21:09:48.058607Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4259844021,"revision":2872,"compact-revision":-1} {"level":"warn","ts":"2026-04-13T21:12:36.918672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.632966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107263"} {"level":"info","ts":"2026-04-13T21:12:36.918771Z","caller":"traceutil/trace.go:171","msg":"trace[1346935164] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6714; }","duration":"110.745518ms","start":"2026-04-13T21:12:36.808005Z","end":"2026-04-13T21:12:36.918751Z","steps":["trace[1346935164] 'range keys from in-memory index tree' (duration: 110.426912ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:33.093784Z","caller":"traceutil/trace.go:171","msg":"trace[524851633] transaction","detail":"{read_only:false; response_revision:7329; number_of_response:1; }","duration":"143.136949ms","start":"2026-04-13T21:14:32.95063Z","end":"2026-04-13T21:14:33.093767Z","steps":["trace[524851633] 'process raft request' (duration: 67.269707ms)","trace[524851633] 'compare' (duration: 75.539084ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:14:33.093966Z","caller":"traceutil/trace.go:171","msg":"trace[1135107511] linearizableReadLoop","detail":"{readStateIndex:7601; appliedIndex:7599; }","duration":"124.459605ms","start":"2026-04-13T21:14:32.969496Z","end":"2026-04-13T21:14:33.093955Z","steps":["trace[1135107511] 'read index received' (duration: 48.47625ms)","trace[1135107511] 'applied index is now lower than readState.Index' (duration: 75.982525ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:14:33.09393Z","caller":"traceutil/trace.go:171","msg":"trace[1050439660] transaction","detail":"{read_only:false; response_revision:7330; number_of_response:1; }","duration":"137.129083ms","start":"2026-04-13T21:14:32.956764Z","end":"2026-04-13T21:14:33.093893Z","steps":["trace[1050439660] 'process raft request' (duration: 136.896087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:33.094053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.536387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:14:33.094074Z","caller":"traceutil/trace.go:171","msg":"trace[1686478539] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:7331; }","duration":"124.603559ms","start":"2026-04-13T21:14:32.969464Z","end":"2026-04-13T21:14:33.094067Z","steps":["trace[1686478539] 'agreement among raft nodes before linearized reading' (duration: 124.542097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:33.09422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.68587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-13T21:14:33.094285Z","caller":"traceutil/trace.go:171","msg":"trace[1929673660] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7331; }","duration":"105.788621ms","start":"2026-04-13T21:14:32.988478Z","end":"2026-04-13T21:14:33.094267Z","steps":["trace[1929673660] 'agreement among raft nodes before linearized reading' (duration: 105.586977ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:48.03347Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5552} {"level":"info","ts":"2026-04-13T21:14:48.400223Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5552,"took":"365.656773ms","hash":3410249921,"current-db-size-bytes":54607872,"current-db-size":"55 MB","current-db-size-in-use-bytes":18391040,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-13T21:14:48.400286Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3410249921,"revision":5552,"compact-revision":2872}