{"level":"warn","ts":"2026-07-23T17:50:34.555392Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T17:50:34.555485Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-07-23T17:50:34.555552Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T17:50:34.555564Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-23T17:50:34.555583Z","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-07-23T17:50:34.556255Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-07-23T17:50:34.55633Z","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.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-07-23T17:50:34.559828Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.276029ms"} {"level":"info","ts":"2026-07-23T17:50:34.565158Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-07-23T17:50:34.565253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T17:50:34.565281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-07-23T17:50:34.565301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T17:50:34.565317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-07-23T17:50:34.565367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-07-23T17:50:34.574449Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T17:50:34.576818Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T17:50:34.580151Z","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-07-23T17:50:34.584909Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T17:50:34.588041Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T17:50:34.588489Z","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-07-23T17:50:34.588622Z","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-07-23T17:50:34.588647Z","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-07-23T17:50:34.589774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-07-23T17:50:34.589958Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-23T17:50:34.590224Z","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-07-23T17:50:34.590303Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-23T17:50:34.590406Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-23T17:50:34.591054Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T17:50:34.591102Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T17:50:34.968155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T17:50:34.968203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T17:50:34.968221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-07-23T17:50:34.968232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-07-23T17:50:34.968237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-23T17:50:34.968245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-07-23T17:50:34.968252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-23T17:50:34.972501Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T17:50:34.972529Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T17:50:34.972624Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T17:50:34.972974Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T17:50:34.973678Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T17:50:34.973882Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T17:50:34.973971Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T17:50:34.974087Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T17:50:34.97413Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T17:50:34.976591Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-07-23T17:50:34.977041Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-23T17:50:37.11592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870945165952476,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T17:50:37.48265Z","caller":"traceutil/trace.go:171","msg":"trace[1797362821] linearizableReadLoop","detail":"{readStateIndex:85; appliedIndex:84; }","duration":"867.074415ms","start":"2026-07-23T17:50:36.615546Z","end":"2026-07-23T17:50:37.48262Z","steps":["trace[1797362821] 'read index received' (duration: 849.329853ms)","trace[1797362821] 'applied index is now lower than readState.Index' (duration: 17.743642ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:50:37.482767Z","caller":"traceutil/trace.go:171","msg":"trace[164299875] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"869.319754ms","start":"2026-07-23T17:50:36.613409Z","end":"2026-07-23T17:50:37.482729Z","steps":["trace[164299875] 'process raft request' (duration: 851.576182ms)","trace[164299875] 'compare' (duration: 17.52514ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:50:37.482859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.470527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-23T17:50:37.482948Z","caller":"traceutil/trace.go:171","msg":"trace[744606720] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:80; }","duration":"677.615328ms","start":"2026-07-23T17:50:36.805318Z","end":"2026-07-23T17:50:37.482933Z","steps":["trace[744606720] 'agreement among raft nodes before linearized reading' (duration: 677.486997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:37.48374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:36.805298Z","time spent":"678.414556ms","remote":"127.0.0.1:45736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":26,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-07-23T17:50:37.483813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.191905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18c4fc8cbaa95213\" ","response":"range_response_count:1 size:654"} {"level":"warn","ts":"2026-07-23T17:50:37.483622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.003179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-23T17:50:37.484008Z","caller":"traceutil/trace.go:171","msg":"trace[1056229615] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:80; }","duration":"264.048668ms","start":"2026-07-23T17:50:37.219927Z","end":"2026-07-23T17:50:37.483975Z","steps":["trace[1056229615] 'agreement among raft nodes before linearized reading' (duration: 262.997828ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:50:37.484024Z","caller":"traceutil/trace.go:171","msg":"trace[67912906] range","detail":"{range_begin:/registry/events/default/instance.18c4fc8cbaa95213; range_end:; response_count:1; response_revision:80; }","duration":"868.408967ms","start":"2026-07-23T17:50:36.615524Z","end":"2026-07-23T17:50:37.483933Z","steps":["trace[67912906] 'agreement among raft nodes before linearized reading' (duration: 867.229676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:37.484077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:36.613398Z","time spent":"869.408724ms","remote":"127.0.0.1:46018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1043,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T17:50:37.484163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:36.615517Z","time spent":"868.583648ms","remote":"127.0.0.1:45666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":676,"request content":"key:\"/registry/events/default/instance.18c4fc8cbaa95213\" "} {"level":"warn","ts":"2026-07-23T17:50:37.484537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.281246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-23T17:50:37.484718Z","caller":"traceutil/trace.go:171","msg":"trace[1200482318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:80; }","duration":"160.413917ms","start":"2026-07-23T17:50:37.324181Z","end":"2026-07-23T17:50:37.484594Z","steps":["trace[1200482318] 'agreement among raft nodes before linearized reading' (duration: 158.719772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:37.485306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.51618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-23T17:50:37.485393Z","caller":"traceutil/trace.go:171","msg":"trace[1350428831] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:80; }","duration":"265.634381ms","start":"2026-07-23T17:50:37.219725Z","end":"2026-07-23T17:50:37.48536Z","steps":["trace[1350428831] 'agreement among raft nodes before linearized reading' (duration: 263.233361ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:50:37.988151Z","caller":"traceutil/trace.go:171","msg":"trace[2002679037] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"211.700599ms","start":"2026-07-23T17:50:37.776403Z","end":"2026-07-23T17:50:37.988103Z","steps":["trace[2002679037] 'process raft request' (duration: 208.099268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:38.316952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.218099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:50:38.31704Z","caller":"traceutil/trace.go:171","msg":"trace[349396626] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:resourcequota-controller; range_end:; response_count:0; response_revision:212; }","duration":"126.348151ms","start":"2026-07-23T17:50:38.19067Z","end":"2026-07-23T17:50:38.317018Z","steps":["trace[349396626] 'range keys from in-memory index tree' (duration: 126.131319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:40.090173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870945165952830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T17:50:40.499619Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.230337535s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T17:50:40.500037Z","caller":"traceutil/trace.go:171","msg":"trace[2025625452] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"1.230824079s","start":"2026-07-23T17:50:39.269187Z","end":"2026-07-23T17:50:40.500011Z","steps":["trace[2025625452] 'process raft request' (duration: 1.230629397s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:40.500284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:39.269168Z","time spent":"1.231034221s","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T17:50:40.507201Z","caller":"traceutil/trace.go:171","msg":"trace[2069107058] linearizableReadLoop","detail":"{readStateIndex:263; appliedIndex:261; }","duration":"917.588873ms","start":"2026-07-23T17:50:39.589594Z","end":"2026-07-23T17:50:40.507183Z","steps":["trace[2069107058] 'read index received' (duration: 910.298751ms)","trace[2069107058] 'applied index is now lower than readState.Index' (duration: 7.288862ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:50:40.507344Z","caller":"traceutil/trace.go:171","msg":"trace[1470242917] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"461.25154ms","start":"2026-07-23T17:50:40.046082Z","end":"2026-07-23T17:50:40.507334Z","steps":["trace[1470242917] 'process raft request' (duration: 460.989058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:40.507486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:40.046058Z","time spent":"461.344121ms","remote":"127.0.0.1:45796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T17:50:40.50751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.908335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-07-23T17:50:40.507613Z","caller":"traceutil/trace.go:171","msg":"trace[771198887] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:257; }","duration":"918.063756ms","start":"2026-07-23T17:50:39.589531Z","end":"2026-07-23T17:50:40.507595Z","steps":["trace[771198887] 'agreement among raft nodes before linearized reading' (duration: 917.871475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:40.507667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:39.589509Z","time spent":"918.137808ms","remote":"127.0.0.1:45780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3731,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-23T17:50:40.508408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.727303ms","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-07-23T17:50:40.508461Z","caller":"traceutil/trace.go:171","msg":"trace[665676738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:258; }","duration":"140.795593ms","start":"2026-07-23T17:50:40.367654Z","end":"2026-07-23T17:50:40.50845Z","steps":["trace[665676738] 'agreement among raft nodes before linearized reading' (duration: 140.705952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:40.508564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.179957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T17:50:40.509102Z","caller":"traceutil/trace.go:171","msg":"trace[311254229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:258; }","duration":"141.741632ms","start":"2026-07-23T17:50:40.367348Z","end":"2026-07-23T17:50:40.509089Z","steps":["trace[311254229] 'agreement among raft nodes before linearized reading' (duration: 140.597322ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:50:41.915055Z","caller":"traceutil/trace.go:171","msg":"trace[1388277115] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"109.041801ms","start":"2026-07-23T17:50:41.805986Z","end":"2026-07-23T17:50:41.915028Z","steps":["trace[1388277115] 'process raft request' (duration: 108.92364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:48.837243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.298031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-23T17:50:48.837361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.696213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-23T17:50:48.83735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.564436ms","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-07-23T17:50:48.837438Z","caller":"traceutil/trace.go:171","msg":"trace[1194241661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:400; }","duration":"266.784438ms","start":"2026-07-23T17:50:48.570632Z","end":"2026-07-23T17:50:48.837417Z","steps":["trace[1194241661] 'range keys from in-memory index tree' (duration: 266.572966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:48.837447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282392405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-07-23T17:50:48.837465Z","caller":"traceutil/trace.go:171","msg":"trace[52337687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"265.682111ms","start":"2026-07-23T17:50:48.571747Z","end":"2026-07-23T17:50:48.83743Z","steps":["trace[52337687] 'range keys from in-memory index tree' (duration: 265.459251ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:50:48.837516Z","caller":"traceutil/trace.go:171","msg":"trace[1826792689] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:400; }","duration":"1.28249291s","start":"2026-07-23T17:50:47.555003Z","end":"2026-07-23T17:50:48.837496Z","steps":["trace[1826792689] 'range keys from in-memory index tree' (duration: 1.28229207s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:48.837575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:47.554989Z","time spent":"1.282572244s","remote":"127.0.0.1:45796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6987,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-07-23T17:50:48.837284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.134496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-23T17:50:48.837649Z","caller":"traceutil/trace.go:171","msg":"trace[771528595] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:400; }","duration":"312.558218ms","start":"2026-07-23T17:50:48.525077Z","end":"2026-07-23T17:50:48.837635Z","steps":["trace[771528595] 'range keys from in-memory index tree' (duration: 312.028881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:48.837685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:48.525059Z","time spent":"312.616971ms","remote":"127.0.0.1:45622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"warn","ts":"2026-07-23T17:50:48.837936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.181497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:50:48.838026Z","caller":"traceutil/trace.go:171","msg":"trace[2008273210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:400; }","duration":"833.316334ms","start":"2026-07-23T17:50:48.004689Z","end":"2026-07-23T17:50:48.838005Z","steps":["trace[2008273210] 'range keys from in-memory index tree' (duration: 832.605159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:48.838074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:48.004669Z","time spent":"833.391228ms","remote":"127.0.0.1:45576","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-07-23T17:50:48.83819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.704293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T17:50:48.838246Z","caller":"traceutil/trace.go:171","msg":"trace[1821653725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:400; }","duration":"265.802017ms","start":"2026-07-23T17:50:48.572429Z","end":"2026-07-23T17:50:48.838231Z","steps":["trace[1821653725] 'range keys from in-memory index tree' (duration: 264.757275ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:50:48.838471Z","caller":"traceutil/trace.go:171","msg":"trace[354433585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:400; }","duration":"116.47888ms","start":"2026-07-23T17:50:48.720865Z","end":"2026-07-23T17:50:48.837343Z","steps":["trace[354433585] 'range keys from in-memory index tree' (duration: 116.155704ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:50:49.193461Z","caller":"traceutil/trace.go:171","msg":"trace[318984191] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:410; }","duration":"352.199815ms","start":"2026-07-23T17:50:48.841232Z","end":"2026-07-23T17:50:49.193432Z","steps":["trace[318984191] 'read index received' (duration: 352.09005ms)","trace[318984191] 'applied index is now lower than readState.Index' (duration: 108.655µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:50:49.193482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:48.839086Z","time spent":"354.388406ms","remote":"127.0.0.1:45622","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-23T17:50:49.193562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.31222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:50:49.193582Z","caller":"traceutil/trace.go:171","msg":"trace[60667387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:400; }","duration":"352.374174ms","start":"2026-07-23T17:50:48.841202Z","end":"2026-07-23T17:50:49.193576Z","steps":["trace[60667387] 'agreement among raft nodes before linearized reading' (duration: 352.317281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:49.193601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:48.841187Z","time spent":"352.410635ms","remote":"127.0.0.1:45590","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-07-23T17:50:49.390728Z","caller":"traceutil/trace.go:171","msg":"trace[2135861829] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"547.861822ms","start":"2026-07-23T17:50:48.842843Z","end":"2026-07-23T17:50:49.390705Z","steps":["trace[2135861829] 'process raft request' (duration: 547.677333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:49.390875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:48.842823Z","time spent":"547.976198ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:50:49.391144Z","caller":"traceutil/trace.go:171","msg":"trace[597029293] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"548.19592ms","start":"2026-07-23T17:50:48.842916Z","end":"2026-07-23T17:50:49.391112Z","steps":["trace[597029293] 'process raft request' (duration: 547.747217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:49.391553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:48.842911Z","time spent":"548.537606ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:50:49.391642Z","caller":"traceutil/trace.go:171","msg":"trace[923877512] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"548.19913ms","start":"2026-07-23T17:50:48.843415Z","end":"2026-07-23T17:50:49.391615Z","steps":["trace[923877512] 'process raft request' (duration: 547.61462ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:50:49.39174Z","caller":"traceutil/trace.go:171","msg":"trace[956421803] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"196.945534ms","start":"2026-07-23T17:50:49.194773Z","end":"2026-07-23T17:50:49.391718Z","steps":["trace[956421803] 'process raft request' (duration: 196.894311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:49.391832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.116293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:50:49.391877Z","caller":"traceutil/trace.go:171","msg":"trace[2113156406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"189.216568ms","start":"2026-07-23T17:50:49.202649Z","end":"2026-07-23T17:50:49.391866Z","steps":["trace[2113156406] 'agreement among raft nodes before linearized reading' (duration: 189.134634ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:50:49.391663Z","caller":"traceutil/trace.go:171","msg":"trace[1142939093] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"547.753398ms","start":"2026-07-23T17:50:48.843893Z","end":"2026-07-23T17:50:49.391646Z","steps":["trace[1142939093] 'process raft request' (duration: 547.631201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:50:49.391914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:48.843408Z","time spent":"548.39543ms","remote":"127.0.0.1:45870","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-07-23T17:50:49.391998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:48.843883Z","time spent":"548.070893ms","remote":"127.0.0.1:45870","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-07-23T17:50:49.391717Z","caller":"traceutil/trace.go:171","msg":"trace[90116453] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"545.869272ms","start":"2026-07-23T17:50:48.845839Z","end":"2026-07-23T17:50:49.391708Z","steps":["trace[90116453] 'process raft request' (duration: 545.761497ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:50:49.391726Z","caller":"traceutil/trace.go:171","msg":"trace[634642015] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:411; }","duration":"189.001068ms","start":"2026-07-23T17:50:49.202701Z","end":"2026-07-23T17:50:49.391702Z","steps":["trace[634642015] 'read index received' (duration: 187.85385ms)","trace[634642015] 'applied index is now lower than readState.Index' (duration: 1.146198ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:50:49.392102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:50:48.845832Z","time spent":"546.21976ms","remote":"127.0.0.1:45796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:51:15.601144Z","caller":"traceutil/trace.go:171","msg":"trace[1139319980] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"224.40081ms","start":"2026-07-23T17:51:15.376694Z","end":"2026-07-23T17:51:15.601095Z","steps":["trace[1139319980] 'process raft request' (duration: 223.175975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:15.604455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.344672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:51:15.604499Z","caller":"traceutil/trace.go:171","msg":"trace[289557101] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:621; }","duration":"201.422445ms","start":"2026-07-23T17:51:15.403066Z","end":"2026-07-23T17:51:15.604489Z","steps":["trace[289557101] 'agreement among raft nodes before linearized reading' (duration: 201.340612ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:51:15.604625Z","caller":"traceutil/trace.go:171","msg":"trace[1187564457] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"221.880938ms","start":"2026-07-23T17:51:15.382737Z","end":"2026-07-23T17:51:15.604618Z","steps":["trace[1187564457] 'process raft request' (duration: 221.545844ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:51:15.604682Z","caller":"traceutil/trace.go:171","msg":"trace[2115268454] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"166.766327ms","start":"2026-07-23T17:51:15.43791Z","end":"2026-07-23T17:51:15.604676Z","steps":["trace[2115268454] 'process raft request' (duration: 166.442474ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:51:15.604732Z","caller":"traceutil/trace.go:171","msg":"trace[386632197] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"201.28066ms","start":"2026-07-23T17:51:15.403094Z","end":"2026-07-23T17:51:15.604375Z","steps":["trace[386632197] 'read index received' (duration: 196.773052ms)","trace[386632197] 'applied index is now lower than readState.Index' (duration: 4.506928ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:51:15.604795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.642304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:51:15.604818Z","caller":"traceutil/trace.go:171","msg":"trace[1858944771] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:621; }","duration":"201.678686ms","start":"2026-07-23T17:51:15.403134Z","end":"2026-07-23T17:51:15.604813Z","steps":["trace[1858944771] 'agreement among raft nodes before linearized reading' (duration: 201.643604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:15.606003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.086646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:51:15.606043Z","caller":"traceutil/trace.go:171","msg":"trace[1565035326] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:621; }","duration":"114.145749ms","start":"2026-07-23T17:51:15.491887Z","end":"2026-07-23T17:51:15.606033Z","steps":["trace[1565035326] 'agreement among raft nodes before linearized reading' (duration: 114.092377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:15.606177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.097663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:51:15.606245Z","caller":"traceutil/trace.go:171","msg":"trace[1608794179] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:621; }","duration":"115.200787ms","start":"2026-07-23T17:51:15.491028Z","end":"2026-07-23T17:51:15.606228Z","steps":["trace[1608794179] 'agreement among raft nodes before linearized reading' (duration: 115.072692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:15.606405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.346093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:51:15.606434Z","caller":"traceutil/trace.go:171","msg":"trace[335012625] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:621; }","duration":"115.399454ms","start":"2026-07-23T17:51:15.491027Z","end":"2026-07-23T17:51:15.606427Z","steps":["trace[335012625] 'agreement among raft nodes before linearized reading' (duration: 115.353603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:15.606526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.92949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:51:15.606553Z","caller":"traceutil/trace.go:171","msg":"trace[150119965] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:621; }","duration":"156.979142ms","start":"2026-07-23T17:51:15.449565Z","end":"2026-07-23T17:51:15.606544Z","steps":["trace[150119965] 'agreement among raft nodes before linearized reading' (duration: 156.94024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:15.606657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.444439ms","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-07-23T17:51:15.606683Z","caller":"traceutil/trace.go:171","msg":"trace[1173367281] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:621; }","duration":"157.498661ms","start":"2026-07-23T17:51:15.449178Z","end":"2026-07-23T17:51:15.606677Z","steps":["trace[1173367281] 'agreement among raft nodes before linearized reading' (duration: 157.46112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:15.606744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.575784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:51:15.606785Z","caller":"traceutil/trace.go:171","msg":"trace[1453573760] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:621; }","duration":"157.641016ms","start":"2026-07-23T17:51:15.449133Z","end":"2026-07-23T17:51:15.606774Z","steps":["trace[1453573760] 'agreement among raft nodes before linearized reading' (duration: 157.582424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:15.60685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.564119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:51:15.606874Z","caller":"traceutil/trace.go:171","msg":"trace[1075468322] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:621; }","duration":"202.61821ms","start":"2026-07-23T17:51:15.404248Z","end":"2026-07-23T17:51:15.606866Z","steps":["trace[1075468322] 'agreement among raft nodes before linearized reading' (duration: 202.587129ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:51:21.820669Z","caller":"traceutil/trace.go:171","msg":"trace[1051085153] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"136.906284ms","start":"2026-07-23T17:51:21.683692Z","end":"2026-07-23T17:51:21.820598Z","steps":["trace[1051085153] 'process raft request' (duration: 136.799141ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:51:21.820674Z","caller":"traceutil/trace.go:171","msg":"trace[300901331] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"136.951066ms","start":"2026-07-23T17:51:21.683684Z","end":"2026-07-23T17:51:21.820635Z","steps":["trace[300901331] 'process raft request' (duration: 112.621536ms)","trace[300901331] 'compare' (duration: 24.04543ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:51:21.820808Z","caller":"traceutil/trace.go:171","msg":"trace[1855665775] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:700; }","duration":"131.391562ms","start":"2026-07-23T17:51:21.689384Z","end":"2026-07-23T17:51:21.820776Z","steps":["trace[1855665775] 'read index received' (duration: 8.439635ms)","trace[1855665775] 'applied index is now lower than readState.Index' (duration: 122.949467ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:51:21.820844Z","caller":"traceutil/trace.go:171","msg":"trace[20610670] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"135.503527ms","start":"2026-07-23T17:51:21.685297Z","end":"2026-07-23T17:51:21.820801Z","steps":["trace[20610670] 'process raft request' (duration: 135.253718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:21.82095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.544789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:51:21.821Z","caller":"traceutil/trace.go:171","msg":"trace[1525220207] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:683; }","duration":"131.631461ms","start":"2026-07-23T17:51:21.689353Z","end":"2026-07-23T17:51:21.820985Z","steps":["trace[1525220207] 'agreement among raft nodes before linearized reading' (duration: 131.528156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:22.463666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.618406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T17:51:22.463793Z","caller":"traceutil/trace.go:171","msg":"trace[1165114774] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"458.994891ms","start":"2026-07-23T17:51:22.004772Z","end":"2026-07-23T17:51:22.463767Z","steps":["trace[1165114774] 'read index received' (duration: 294.030643ms)","trace[1165114774] 'applied index is now lower than readState.Index' (duration: 164.962408ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:51:22.463948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.154787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:51:22.46404Z","caller":"traceutil/trace.go:171","msg":"trace[1802673688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"459.290942ms","start":"2026-07-23T17:51:22.004726Z","end":"2026-07-23T17:51:22.464017Z","steps":["trace[1802673688] 'agreement among raft nodes before linearized reading' (duration: 459.121066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:22.464095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:51:22.004708Z","time spent":"459.378874ms","remote":"127.0.0.1:45590","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-07-23T17:51:22.464043Z","caller":"traceutil/trace.go:171","msg":"trace[1872966472] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"611.704209ms","start":"2026-07-23T17:51:21.852085Z","end":"2026-07-23T17:51:22.463789Z","steps":["trace[1872966472] 'process raft request' (duration: 446.681099ms)","trace[1872966472] 'compare' (duration: 164.213252ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:51:22.464594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:51:21.852058Z","time spent":"612.301109ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T17:51:22.493377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.001818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kgtcm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-23T17:51:22.493386Z","caller":"traceutil/trace.go:171","msg":"trace[419863394] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"267.784746ms","start":"2026-07-23T17:51:22.225567Z","end":"2026-07-23T17:51:22.493352Z","steps":["trace[419863394] 'process raft request' (duration: 266.996098ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:51:22.493459Z","caller":"traceutil/trace.go:171","msg":"trace[1006197402] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kgtcm; range_end:; response_count:1; response_revision:685; }","duration":"419.110542ms","start":"2026-07-23T17:51:22.074323Z","end":"2026-07-23T17:51:22.493433Z","steps":["trace[1006197402] 'agreement among raft nodes before linearized reading' (duration: 418.919235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:22.493511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:51:22.074304Z","time spent":"419.197184ms","remote":"127.0.0.1:45796","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-kgtcm\" "} {"level":"warn","ts":"2026-07-23T17:51:23.171611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870945165953747,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T17:51:23.46482Z","caller":"traceutil/trace.go:171","msg":"trace[237756530] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"954.787205ms","start":"2026-07-23T17:51:22.51001Z","end":"2026-07-23T17:51:23.464797Z","steps":["trace[237756530] 'process raft request' (duration: 954.63508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:23.464976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:51:22.509985Z","time spent":"954.904809ms","remote":"127.0.0.1:45796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:51:23.500831Z","caller":"traceutil/trace.go:171","msg":"trace[2020686358] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:706; }","duration":"830.006244ms","start":"2026-07-23T17:51:22.670776Z","end":"2026-07-23T17:51:23.500782Z","steps":["trace[2020686358] 'read index received' (duration: 793.888497ms)","trace[2020686358] 'applied index is now lower than readState.Index' (duration: 36.116867ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:51:23.500857Z","caller":"traceutil/trace.go:171","msg":"trace[2018378857] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"949.866611ms","start":"2026-07-23T17:51:22.550957Z","end":"2026-07-23T17:51:23.500823Z","steps":["trace[2018378857] 'process raft request' (duration: 935.497079ms)","trace[2018378857] 'compare' (duration: 14.221497ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:51:23.500968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:51:22.550932Z","time spent":"949.982566ms","remote":"127.0.0.1:45952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T17:51:23.501027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.236622ms","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-07-23T17:51:23.501056Z","caller":"traceutil/trace.go:171","msg":"trace[92637777] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"830.317695ms","start":"2026-07-23T17:51:22.670729Z","end":"2026-07-23T17:51:23.501047Z","steps":["trace[92637777] 'agreement among raft nodes before linearized reading' (duration: 830.190971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:23.501079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:51:22.670709Z","time spent":"830.364396ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T17:51:23.50111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.168868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:51:23.50114Z","caller":"traceutil/trace.go:171","msg":"trace[2016672646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"497.273661ms","start":"2026-07-23T17:51:23.003857Z","end":"2026-07-23T17:51:23.50113Z","steps":["trace[2016672646] 'agreement among raft nodes before linearized reading' (duration: 497.216629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:23.501165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:51:23.00384Z","time spent":"497.320693ms","remote":"127.0.0.1:45576","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-07-23T17:51:38.495073Z","caller":"traceutil/trace.go:171","msg":"trace[81368859] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"323.19186ms","start":"2026-07-23T17:51:38.171837Z","end":"2026-07-23T17:51:38.495029Z","steps":["trace[81368859] 'process raft request' (duration: 322.951952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:51:38.495253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:51:38.171821Z","time spent":"323.342624ms","remote":"127.0.0.1:45796","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-07-23T17:52:34.196052Z","caller":"traceutil/trace.go:171","msg":"trace[693534871] linearizableReadLoop","detail":"{readStateIndex:1519; appliedIndex:1518; }","duration":"375.86497ms","start":"2026-07-23T17:52:33.820152Z","end":"2026-07-23T17:52:34.196017Z","steps":["trace[693534871] 'read index received' (duration: 372.525646ms)","trace[693534871] 'applied index is now lower than readState.Index' (duration: 3.337484ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:52:34.196343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.167176ms","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-07-23T17:52:34.19648Z","caller":"traceutil/trace.go:171","msg":"trace[1355298347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1475; }","duration":"376.350909ms","start":"2026-07-23T17:52:33.820083Z","end":"2026-07-23T17:52:34.196434Z","steps":["trace[1355298347] 'agreement among raft nodes before linearized reading' (duration: 375.991422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:52:34.196539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:52:33.820063Z","time spent":"376.463482ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T17:52:34.200179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.849651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T17:52:34.20045Z","caller":"traceutil/trace.go:171","msg":"trace[1209394808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1476; }","duration":"157.15667ms","start":"2026-07-23T17:52:34.043272Z","end":"2026-07-23T17:52:34.200428Z","steps":["trace[1209394808] 'agreement among raft nodes before linearized reading' (duration: 156.781821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:52:34.200746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.176196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:52:34.200804Z","caller":"traceutil/trace.go:171","msg":"trace[2057806486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1476; }","duration":"195.267777ms","start":"2026-07-23T17:52:34.00552Z","end":"2026-07-23T17:52:34.200788Z","steps":["trace[2057806486] 'agreement among raft nodes before linearized reading' (duration: 195.140555ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:52:59.83385Z","caller":"traceutil/trace.go:171","msg":"trace[1981664680] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1974; }","duration":"109.914673ms","start":"2026-07-23T17:52:59.723906Z","end":"2026-07-23T17:52:59.833821Z","steps":["trace[1981664680] 'read index received' (duration: 109.68945ms)","trace[1981664680] 'applied index is now lower than readState.Index' (duration: 224.003µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:52:59.834127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.206268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c4fcae2cf896f1\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-07-23T17:52:59.834183Z","caller":"traceutil/trace.go:171","msg":"trace[977946710] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c4fcae2cf896f1; range_end:; response_count:1; response_revision:1903; }","duration":"110.31486ms","start":"2026-07-23T17:52:59.723857Z","end":"2026-07-23T17:52:59.834171Z","steps":["trace[977946710] 'agreement among raft nodes before linearized reading' (duration: 110.104947ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:52:59.833934Z","caller":"traceutil/trace.go:171","msg":"trace[1500071753] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"265.1068ms","start":"2026-07-23T17:52:59.568803Z","end":"2026-07-23T17:52:59.833909Z","steps":["trace[1500071753] 'process raft request' (duration: 264.857776ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:00.131882Z","caller":"traceutil/trace.go:171","msg":"trace[1176870260] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"294.604342ms","start":"2026-07-23T17:52:59.837249Z","end":"2026-07-23T17:53:00.131853Z","steps":["trace[1176870260] 'process raft request' (duration: 282.166661ms)","trace[1176870260] 'compare' (duration: 11.996153ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:53:00.151561Z","caller":"traceutil/trace.go:171","msg":"trace[1438070229] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1975; }","duration":"143.865162ms","start":"2026-07-23T17:53:00.007672Z","end":"2026-07-23T17:53:00.151537Z","steps":["trace[1438070229] 'read index received' (duration: 111.730987ms)","trace[1438070229] 'applied index is now lower than readState.Index' (duration: 32.133005ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:53:00.151634Z","caller":"traceutil/trace.go:171","msg":"trace[855957296] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"239.738135ms","start":"2026-07-23T17:52:59.911871Z","end":"2026-07-23T17:53:00.151609Z","steps":["trace[855957296] 'process raft request' (duration: 239.527522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:00.151828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.135136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:53:00.15187Z","caller":"traceutil/trace.go:171","msg":"trace[1175332332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1905; }","duration":"144.230077ms","start":"2026-07-23T17:53:00.007629Z","end":"2026-07-23T17:53:00.151859Z","steps":["trace[1175332332] 'agreement among raft nodes before linearized reading' (duration: 143.997933ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:12.679331Z","caller":"traceutil/trace.go:171","msg":"trace[1318739454] linearizableReadLoop","detail":"{readStateIndex:2081; appliedIndex:2080; }","duration":"222.817717ms","start":"2026-07-23T17:53:12.456489Z","end":"2026-07-23T17:53:12.679306Z","steps":["trace[1318739454] 'read index received' (duration: 222.672464ms)","trace[1318739454] 'applied index is now lower than readState.Index' (duration: 144.003µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:53:12.679496Z","caller":"traceutil/trace.go:171","msg":"trace[1543348452] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"241.642381ms","start":"2026-07-23T17:53:12.437818Z","end":"2026-07-23T17:53:12.679461Z","steps":["trace[1543348452] 'process raft request' (duration: 241.295556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:12.679629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.122582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T17:53:12.679683Z","caller":"traceutil/trace.go:171","msg":"trace[2001280848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2006; }","duration":"223.190814ms","start":"2026-07-23T17:53:12.456475Z","end":"2026-07-23T17:53:12.679666Z","steps":["trace[2001280848] 'agreement among raft nodes before linearized reading' (duration: 222.98237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:12.687467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.904827ms","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-07-23T17:53:12.687537Z","caller":"traceutil/trace.go:171","msg":"trace[1317801388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2007; }","duration":"226.051289ms","start":"2026-07-23T17:53:12.46147Z","end":"2026-07-23T17:53:12.687522Z","steps":["trace[1317801388] 'agreement among raft nodes before linearized reading' (duration: 225.870696ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:12.687582Z","caller":"traceutil/trace.go:171","msg":"trace[1730783722] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"224.18453ms","start":"2026-07-23T17:53:12.463371Z","end":"2026-07-23T17:53:12.687556Z","steps":["trace[1730783722] 'process raft request' (duration: 223.863005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:12.68778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.45401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T17:53:12.687825Z","caller":"traceutil/trace.go:171","msg":"trace[1381187169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2007; }","duration":"225.576892ms","start":"2026-07-23T17:53:12.462238Z","end":"2026-07-23T17:53:12.687815Z","steps":["trace[1381187169] 'agreement among raft nodes before linearized reading' (duration: 225.394879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:12.687827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.229202ms","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-07-23T17:53:12.687869Z","caller":"traceutil/trace.go:171","msg":"trace[149252123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2007; }","duration":"226.301214ms","start":"2026-07-23T17:53:12.461559Z","end":"2026-07-23T17:53:12.68786Z","steps":["trace[149252123] 'agreement among raft nodes before linearized reading' (duration: 226.148371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:15.853019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.70118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-w8g2f\" ","response":"range_response_count:1 size:6147"} {"level":"info","ts":"2026-07-23T17:53:15.853093Z","caller":"traceutil/trace.go:171","msg":"trace[63133700] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-w8g2f; range_end:; response_count:1; response_revision:2030; }","duration":"357.831551ms","start":"2026-07-23T17:53:15.495245Z","end":"2026-07-23T17:53:15.853076Z","steps":["trace[63133700] 'range keys from in-memory index tree' (duration: 357.498366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:15.85314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:15.495226Z","time spent":"357.904853ms","remote":"127.0.0.1:45796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":6169,"request content":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-w8g2f\" "} {"level":"info","ts":"2026-07-23T17:53:15.85334Z","caller":"traceutil/trace.go:171","msg":"trace[1249073495] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"239.760537ms","start":"2026-07-23T17:53:15.613555Z","end":"2026-07-23T17:53:15.853316Z","steps":["trace[1249073495] 'process raft request' (duration: 238.984765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:16.445805Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870945165956375,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T17:53:16.94644Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870945165956375,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T17:53:17.044251Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.180672404s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T17:53:17.047743Z","caller":"traceutil/trace.go:171","msg":"trace[18137720] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"1.184254941s","start":"2026-07-23T17:53:15.863458Z","end":"2026-07-23T17:53:17.047713Z","steps":["trace[18137720] 'process raft request' (duration: 1.180998029s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:17.047926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:15.863436Z","time spent":"1.184382263s","remote":"127.0.0.1:45796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5918,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:53:17.061795Z","caller":"traceutil/trace.go:171","msg":"trace[1629861267] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"919.47224ms","start":"2026-07-23T17:53:16.142303Z","end":"2026-07-23T17:53:17.061775Z","steps":["trace[1629861267] 'process raft request' (duration: 919.110034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:17.061925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:16.142277Z","time spent":"919.575781ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:53:17.061882Z","caller":"traceutil/trace.go:171","msg":"trace[927390082] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"848.462501ms","start":"2026-07-23T17:53:16.213388Z","end":"2026-07-23T17:53:17.061851Z","steps":["trace[927390082] 'process raft request' (duration: 848.260358ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:17.062125Z","caller":"traceutil/trace.go:171","msg":"trace[587780956] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"778.254165ms","start":"2026-07-23T17:53:16.283836Z","end":"2026-07-23T17:53:17.06209Z","steps":["trace[587780956] 'process raft request' (duration: 777.901339ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:17.062233Z","caller":"traceutil/trace.go:171","msg":"trace[1254515196] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"363.698301ms","start":"2026-07-23T17:53:16.698519Z","end":"2026-07-23T17:53:17.062217Z","steps":["trace[1254515196] 'process raft request' (duration: 363.474197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:17.062254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:16.283803Z","time spent":"778.386506ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:53:17.062232Z","caller":"traceutil/trace.go:171","msg":"trace[1036822738] linearizableReadLoop","detail":"{readStateIndex:2113; appliedIndex:2107; }","duration":"1.116896504s","start":"2026-07-23T17:53:15.94527Z","end":"2026-07-23T17:53:17.062166Z","steps":["trace[1036822738] 'read index received' (duration: 1.099201424s)","trace[1036822738] 'applied index is now lower than readState.Index' (duration: 17.6932ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:53:17.06229Z","caller":"traceutil/trace.go:171","msg":"trace[1430427374] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"273.455816ms","start":"2026-07-23T17:53:16.788823Z","end":"2026-07-23T17:53:17.062278Z","steps":["trace[1430427374] 'process raft request' (duration: 273.291303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:17.062358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:16.698323Z","time spent":"363.940234ms","remote":"127.0.0.1:45870","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-07-23T17:53:17.062536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117261219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-23T17:53:17.06375Z","caller":"traceutil/trace.go:171","msg":"trace[481946178] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2037; }","duration":"1.118515299s","start":"2026-07-23T17:53:15.945217Z","end":"2026-07-23T17:53:17.063732Z","steps":["trace[481946178] 'agreement among raft nodes before linearized reading' (duration: 1.117136338s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:17.063858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.476884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-23T17:53:17.063878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.882089ms","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-07-23T17:53:17.063892Z","caller":"traceutil/trace.go:171","msg":"trace[1725665117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2041; }","duration":"359.532475ms","start":"2026-07-23T17:53:16.70435Z","end":"2026-07-23T17:53:17.063883Z","steps":["trace[1725665117] 'agreement among raft nodes before linearized reading' (duration: 359.426433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:17.063916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:16.704337Z","time spent":"359.574816ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T17:53:17.063902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.857704ms","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-07-23T17:53:17.063916Z","caller":"traceutil/trace.go:171","msg":"trace[1080136901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2041; }","duration":"369.968272ms","start":"2026-07-23T17:53:16.693938Z","end":"2026-07-23T17:53:17.063906Z","steps":["trace[1080136901] 'agreement among raft nodes before linearized reading' (duration: 369.84871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:17.063959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:16.693917Z","time spent":"370.035833ms","remote":"127.0.0.1:45870","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":"info","ts":"2026-07-23T17:53:17.063962Z","caller":"traceutil/trace.go:171","msg":"trace[1183946522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2041; }","duration":"280.956725ms","start":"2026-07-23T17:53:16.782989Z","end":"2026-07-23T17:53:17.063946Z","steps":["trace[1183946522] 'agreement among raft nodes before linearized reading' (duration: 280.775353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:17.063627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057482954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:53:17.064195Z","caller":"traceutil/trace.go:171","msg":"trace[1478314175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2041; }","duration":"1.058088505s","start":"2026-07-23T17:53:16.006093Z","end":"2026-07-23T17:53:17.064181Z","steps":["trace[1478314175] 'agreement among raft nodes before linearized reading' (duration: 1.057489045s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:17.064238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:16.006072Z","time spent":"1.058154186s","remote":"127.0.0.1:45576","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-07-23T17:53:17.064339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.032959ms","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-07-23T17:53:17.064384Z","caller":"traceutil/trace.go:171","msg":"trace[1546907592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2041; }","duration":"278.09459ms","start":"2026-07-23T17:53:16.786278Z","end":"2026-07-23T17:53:17.064373Z","steps":["trace[1546907592] 'agreement among raft nodes before linearized reading' (duration: 277.982018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:17.064438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.205022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T17:53:17.064462Z","caller":"traceutil/trace.go:171","msg":"trace[1388484406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2041; }","duration":"278.287403ms","start":"2026-07-23T17:53:16.786169Z","end":"2026-07-23T17:53:17.064456Z","steps":["trace[1388484406] 'agreement among raft nodes before linearized reading' (duration: 278.231113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:17.062641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:16.213346Z","time spent":"849.258653ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T17:53:17.063958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:15.945194Z","time spent":"1.118682252s","remote":"127.0.0.1:44624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-23T17:53:19.304942Z","caller":"traceutil/trace.go:171","msg":"trace[908110343] linearizableReadLoop","detail":"{readStateIndex:2127; appliedIndex:2126; }","duration":"300.973615ms","start":"2026-07-23T17:53:19.003945Z","end":"2026-07-23T17:53:19.304918Z","steps":["trace[908110343] 'read index received' (duration: 300.842242ms)","trace[908110343] 'applied index is now lower than readState.Index' (duration: 130.083µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:53:19.30501Z","caller":"traceutil/trace.go:171","msg":"trace[206257872] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"345.993206ms","start":"2026-07-23T17:53:18.958975Z","end":"2026-07-23T17:53:19.304968Z","steps":["trace[206257872] 'process raft request' (duration: 345.791392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:19.305132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.163698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T17:53:19.305197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:18.958952Z","time spent":"346.138648ms","remote":"127.0.0.1:45870","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-07-23T17:53:19.305248Z","caller":"traceutil/trace.go:171","msg":"trace[588028275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2050; }","duration":"301.328681ms","start":"2026-07-23T17:53:19.003895Z","end":"2026-07-23T17:53:19.305224Z","steps":["trace[588028275] 'agreement among raft nodes before linearized reading' (duration: 301.140558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:19.305316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:19.003875Z","time spent":"301.425352ms","remote":"127.0.0.1:45576","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-07-23T17:53:19.317236Z","caller":"traceutil/trace.go:171","msg":"trace[759974893] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"248.736184ms","start":"2026-07-23T17:53:19.068477Z","end":"2026-07-23T17:53:19.317214Z","steps":["trace[759974893] 'process raft request' (duration: 248.647772ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:19.317241Z","caller":"traceutil/trace.go:171","msg":"trace[927412955] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"250.43326ms","start":"2026-07-23T17:53:19.06678Z","end":"2026-07-23T17:53:19.317213Z","steps":["trace[927412955] 'process raft request' (duration: 250.214426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:19.317451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.294221ms","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-07-23T17:53:19.317488Z","caller":"traceutil/trace.go:171","msg":"trace[1788070436] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2053; }","duration":"240.339662ms","start":"2026-07-23T17:53:19.077138Z","end":"2026-07-23T17:53:19.317478Z","steps":["trace[1788070436] 'agreement among raft nodes before linearized reading' (duration: 240.23664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:19.317535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.770233ms","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-07-23T17:53:19.31759Z","caller":"traceutil/trace.go:171","msg":"trace[2140568859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2053; }","duration":"241.837624ms","start":"2026-07-23T17:53:19.075731Z","end":"2026-07-23T17:53:19.317568Z","steps":["trace[2140568859] 'agreement among raft nodes before linearized reading' (duration: 241.709112ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:19.317395Z","caller":"traceutil/trace.go:171","msg":"trace[128943105] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"247.326881ms","start":"2026-07-23T17:53:19.070049Z","end":"2026-07-23T17:53:19.317375Z","steps":["trace[128943105] 'process raft request' (duration: 247.119698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:19.317709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.878701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T17:53:19.317733Z","caller":"traceutil/trace.go:171","msg":"trace[651980030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2053; }","duration":"242.926782ms","start":"2026-07-23T17:53:19.074799Z","end":"2026-07-23T17:53:19.317726Z","steps":["trace[651980030] 'agreement among raft nodes before linearized reading' (duration: 242.865842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:19.317726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.925345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T17:53:19.317772Z","caller":"traceutil/trace.go:171","msg":"trace[1651722387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2053; }","duration":"239.973786ms","start":"2026-07-23T17:53:19.077785Z","end":"2026-07-23T17:53:19.317758Z","steps":["trace[1651722387] 'agreement among raft nodes before linearized reading' (duration: 239.804343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:19.317779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.480963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-23T17:53:19.317869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.565908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-23T17:53:19.317891Z","caller":"traceutil/trace.go:171","msg":"trace[2048960714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2053; }","duration":"240.599066ms","start":"2026-07-23T17:53:19.077282Z","end":"2026-07-23T17:53:19.317881Z","steps":["trace[2048960714] 'agreement among raft nodes before linearized reading' (duration: 240.437373ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:19.317905Z","caller":"traceutil/trace.go:171","msg":"trace[528126762] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2053; }","duration":"237.624439ms","start":"2026-07-23T17:53:19.080267Z","end":"2026-07-23T17:53:19.317892Z","steps":["trace[528126762] 'agreement among raft nodes before linearized reading' (duration: 237.544058ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:22.166763Z","caller":"traceutil/trace.go:171","msg":"trace[117564868] linearizableReadLoop","detail":"{readStateIndex:2145; appliedIndex:2144; }","duration":"161.191649ms","start":"2026-07-23T17:53:22.005547Z","end":"2026-07-23T17:53:22.166739Z","steps":["trace[117564868] 'read index received' (duration: 160.971405ms)","trace[117564868] 'applied index is now lower than readState.Index' (duration: 219.314µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:53:22.166912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.349882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:53:22.166949Z","caller":"traceutil/trace.go:171","msg":"trace[265413349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2068; }","duration":"161.430953ms","start":"2026-07-23T17:53:22.005508Z","end":"2026-07-23T17:53:22.166939Z","steps":["trace[265413349] 'agreement among raft nodes before linearized reading' (duration: 161.341781ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:22.167324Z","caller":"traceutil/trace.go:171","msg":"trace[961716666] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"298.421332ms","start":"2026-07-23T17:53:21.868891Z","end":"2026-07-23T17:53:22.167313Z","steps":["trace[961716666] 'process raft request' (duration: 297.693381ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:33.654499Z","caller":"traceutil/trace.go:171","msg":"trace[455535208] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"173.411266ms","start":"2026-07-23T17:53:33.48107Z","end":"2026-07-23T17:53:33.654481Z","steps":["trace[455535208] 'process raft request' (duration: 168.208228ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:33.65601Z","caller":"traceutil/trace.go:171","msg":"trace[891976377] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"174.462781ms","start":"2026-07-23T17:53:33.481522Z","end":"2026-07-23T17:53:33.655985Z","steps":["trace[891976377] 'process raft request' (duration: 174.282769ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:33.656296Z","caller":"traceutil/trace.go:171","msg":"trace[1217834528] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"174.759997ms","start":"2026-07-23T17:53:33.481522Z","end":"2026-07-23T17:53:33.656282Z","steps":["trace[1217834528] 'process raft request' (duration: 174.638374ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:33.656273Z","caller":"traceutil/trace.go:171","msg":"trace[1126432978] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"174.726646ms","start":"2026-07-23T17:53:33.481513Z","end":"2026-07-23T17:53:33.656239Z","steps":["trace[1126432978] 'process raft request' (duration: 174.430331ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:33.65643Z","caller":"traceutil/trace.go:171","msg":"trace[586721411] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"174.790197ms","start":"2026-07-23T17:53:33.481607Z","end":"2026-07-23T17:53:33.656397Z","steps":["trace[586721411] 'process raft request' (duration: 174.632525ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:39.673163Z","caller":"traceutil/trace.go:171","msg":"trace[840094457] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"179.098946ms","start":"2026-07-23T17:53:39.494033Z","end":"2026-07-23T17:53:39.673132Z","steps":["trace[840094457] 'process raft request' (duration: 82.118113ms)","trace[840094457] 'compare' (duration: 96.826951ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:53:39.694189Z","caller":"traceutil/trace.go:171","msg":"trace[2134077181] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2243; }","duration":"177.544243ms","start":"2026-07-23T17:53:39.516629Z","end":"2026-07-23T17:53:39.694173Z","steps":["trace[2134077181] 'read index received' (duration: 59.5414ms)","trace[2134077181] 'applied index is now lower than readState.Index' (duration: 118.002113ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:53:39.694338Z","caller":"traceutil/trace.go:171","msg":"trace[994297970] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"196.737166ms","start":"2026-07-23T17:53:39.497538Z","end":"2026-07-23T17:53:39.694275Z","steps":["trace[994297970] 'process raft request' (duration: 196.559164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:39.694511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.856087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-23T17:53:39.694588Z","caller":"traceutil/trace.go:171","msg":"trace[939804672] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2164; }","duration":"177.971959ms","start":"2026-07-23T17:53:39.516595Z","end":"2026-07-23T17:53:39.694567Z","steps":["trace[939804672] 'agreement among raft nodes before linearized reading' (duration: 177.695245ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:39.727737Z","caller":"traceutil/trace.go:171","msg":"trace[1381308615] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"147.571991ms","start":"2026-07-23T17:53:39.580137Z","end":"2026-07-23T17:53:39.727709Z","steps":["trace[1381308615] 'process raft request' (duration: 146.81419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:40.496734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.331229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T17:53:40.496944Z","caller":"traceutil/trace.go:171","msg":"trace[2142074351] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"700.476648ms","start":"2026-07-23T17:53:39.796451Z","end":"2026-07-23T17:53:40.496927Z","steps":["trace[2142074351] 'process raft request' (duration: 700.449107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:40.496989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:39.796429Z","time spent":"700.539099ms","remote":"127.0.0.1:45870","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-07-23T17:53:40.497083Z","caller":"traceutil/trace.go:171","msg":"trace[943584935] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"764.20985ms","start":"2026-07-23T17:53:39.732861Z","end":"2026-07-23T17:53:40.497071Z","steps":["trace[943584935] 'process raft request' (duration: 763.946465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:40.497128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:39.732854Z","time spent":"764.25283ms","remote":"127.0.0.1:45870","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-07-23T17:53:40.497113Z","caller":"traceutil/trace.go:171","msg":"trace[1636951175] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"764.145377ms","start":"2026-07-23T17:53:39.732914Z","end":"2026-07-23T17:53:40.497059Z","steps":["trace[1636951175] 'process raft request' (duration: 763.931204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:40.497319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:39.732908Z","time spent":"764.302172ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:53:40.497355Z","caller":"traceutil/trace.go:171","msg":"trace[882605688] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"763.831825ms","start":"2026-07-23T17:53:39.733515Z","end":"2026-07-23T17:53:40.497347Z","steps":["trace[882605688] 'process raft request' (duration: 763.355107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:40.497402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:39.733502Z","time spent":"763.876255ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:53:40.497515Z","caller":"traceutil/trace.go:171","msg":"trace[1826213882] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"764.220529ms","start":"2026-07-23T17:53:39.732779Z","end":"2026-07-23T17:53:40.497Z","steps":["trace[1826213882] 'process raft request' (duration: 121.564145ms)","trace[1826213882] 'compare' (duration: 642.059445ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:53:40.497849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:39.73277Z","time spent":"764.94766ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:53:40.566602Z","caller":"traceutil/trace.go:171","msg":"trace[765620398] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"336.277874ms","start":"2026-07-23T17:53:40.230303Z","end":"2026-07-23T17:53:40.566581Z","steps":["trace[765620398] 'process raft request' (duration: 336.161622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:40.566841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:40.230281Z","time spent":"336.380616ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:53:42.012735Z","caller":"traceutil/trace.go:171","msg":"trace[384878070] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"573.420275ms","start":"2026-07-23T17:53:41.439286Z","end":"2026-07-23T17:53:42.012706Z","steps":["trace[384878070] 'process raft request' (duration: 573.083679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:42.012895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:41.439264Z","time spent":"573.548726ms","remote":"127.0.0.1:45870","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-07-23T17:53:42.014737Z","caller":"traceutil/trace.go:171","msg":"trace[503249236] linearizableReadLoop","detail":"{readStateIndex:2256; appliedIndex:2253; }","duration":"259.508112ms","start":"2026-07-23T17:53:41.755204Z","end":"2026-07-23T17:53:42.014712Z","steps":["trace[503249236] 'read index received' (duration: 257.224778ms)","trace[503249236] 'applied index is now lower than readState.Index' (duration: 2.282233ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:53:42.01491Z","caller":"traceutil/trace.go:171","msg":"trace[893400037] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"336.027457ms","start":"2026-07-23T17:53:41.678846Z","end":"2026-07-23T17:53:42.014874Z","steps":["trace[893400037] 'process raft request' (duration: 335.624841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:42.01534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.00592ms","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-07-23T17:53:42.015402Z","caller":"traceutil/trace.go:171","msg":"trace[1147772070] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2175; }","duration":"175.116062ms","start":"2026-07-23T17:53:41.840271Z","end":"2026-07-23T17:53:42.015387Z","steps":["trace[1147772070] 'agreement among raft nodes before linearized reading' (duration: 174.98416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:42.01508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.855606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-23T17:53:42.01545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:41.678823Z","time spent":"336.530454ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:53:42.015476Z","caller":"traceutil/trace.go:171","msg":"trace[727316759] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2175; }","duration":"260.284513ms","start":"2026-07-23T17:53:41.755168Z","end":"2026-07-23T17:53:42.015453Z","steps":["trace[727316759] 'agreement among raft nodes before linearized reading' (duration: 259.773385ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:42.015118Z","caller":"traceutil/trace.go:171","msg":"trace[1519973652] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"315.696967ms","start":"2026-07-23T17:53:41.699381Z","end":"2026-07-23T17:53:42.015078Z","steps":["trace[1519973652] 'process raft request' (duration: 315.271181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:42.015658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:41.699366Z","time spent":"316.213876ms","remote":"127.0.0.1:45870","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-07-23T17:53:42.01662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.310169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T17:53:42.016715Z","caller":"traceutil/trace.go:171","msg":"trace[1496041242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2175; }","duration":"244.767005ms","start":"2026-07-23T17:53:41.771924Z","end":"2026-07-23T17:53:42.016691Z","steps":["trace[1496041242] 'agreement among raft nodes before linearized reading' (duration: 243.293623ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:42.57683Z","caller":"traceutil/trace.go:171","msg":"trace[418466220] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"554.593116ms","start":"2026-07-23T17:53:42.022212Z","end":"2026-07-23T17:53:42.576805Z","steps":["trace[418466220] 'process raft request' (duration: 531.420007ms)","trace[418466220] 'compare' (duration: 22.232756ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:53:42.576805Z","caller":"traceutil/trace.go:171","msg":"trace[49089698] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2256; }","duration":"357.955456ms","start":"2026-07-23T17:53:42.218829Z","end":"2026-07-23T17:53:42.576785Z","steps":["trace[49089698] 'read index received' (duration: 334.759526ms)","trace[49089698] 'applied index is now lower than readState.Index' (duration: 23.19467ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:53:42.577019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.186669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T17:53:42.577059Z","caller":"traceutil/trace.go:171","msg":"trace[1832210579] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2176; }","duration":"358.2825ms","start":"2026-07-23T17:53:42.218766Z","end":"2026-07-23T17:53:42.577048Z","steps":["trace[1832210579] 'agreement among raft nodes before linearized reading' (duration: 358.131988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:42.577084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:42.218743Z","time spent":"358.334191ms","remote":"127.0.0.1:45926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":4,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-07-23T17:53:42.577035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:42.022186Z","time spent":"554.77937ms","remote":"127.0.0.1:45870","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-07-23T17:53:43.182769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.295911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T17:53:43.182873Z","caller":"traceutil/trace.go:171","msg":"trace[637284421] linearizableReadLoop","detail":"{readStateIndex:2258; appliedIndex:2257; }","duration":"605.8101ms","start":"2026-07-23T17:53:42.577046Z","end":"2026-07-23T17:53:43.182856Z","steps":["trace[637284421] 'read index received' (duration: 441.380698ms)","trace[637284421] 'applied index is now lower than readState.Index' (duration: 164.428042ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:53:43.182996Z","caller":"traceutil/trace.go:171","msg":"trace[1305821704] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"611.19247ms","start":"2026-07-23T17:53:42.571767Z","end":"2026-07-23T17:53:43.182959Z","steps":["trace[1305821704] 'process raft request' (duration: 446.627885ms)","trace[1305821704] 'compare' (duration: 164.102058ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:53:43.183076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:42.571739Z","time spent":"611.298401ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T17:53:43.183219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.498105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-23T17:53:43.183219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.584886ms","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-07-23T17:53:43.183276Z","caller":"traceutil/trace.go:171","msg":"trace[1434204969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2177; }","duration":"680.605747ms","start":"2026-07-23T17:53:42.502657Z","end":"2026-07-23T17:53:43.183263Z","steps":["trace[1434204969] 'agreement among raft nodes before linearized reading' (duration: 680.449005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:43.183263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.957628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-23T17:53:43.183279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.596257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-23T17:53:43.183306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:42.502634Z","time spent":"680.666327ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T17:53:43.183314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.633099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c4fcb5355bad02\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-23T17:53:43.183337Z","caller":"traceutil/trace.go:171","msg":"trace[1899504927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2177; }","duration":"680.698048ms","start":"2026-07-23T17:53:42.502627Z","end":"2026-07-23T17:53:43.183325Z","steps":["trace[1899504927] 'agreement among raft nodes before linearized reading' (duration: 680.519275ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:43.18334Z","caller":"traceutil/trace.go:171","msg":"trace[1520258328] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2177; }","duration":"532.108971ms","start":"2026-07-23T17:53:42.651209Z","end":"2026-07-23T17:53:43.183318Z","steps":["trace[1520258328] 'agreement among raft nodes before linearized reading' (duration: 531.932358ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:43.183284Z","caller":"traceutil/trace.go:171","msg":"trace[1957842208] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2177; }","duration":"680.678268ms","start":"2026-07-23T17:53:42.50259Z","end":"2026-07-23T17:53:43.183269Z","steps":["trace[1957842208] 'agreement among raft nodes before linearized reading' (duration: 680.514006ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:43.183369Z","caller":"traceutil/trace.go:171","msg":"trace[1216470985] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c4fcb5355bad02; range_end:; response_count:1; response_revision:2177; }","duration":"614.747341ms","start":"2026-07-23T17:53:42.568605Z","end":"2026-07-23T17:53:43.183353Z","steps":["trace[1216470985] 'agreement among raft nodes before linearized reading' (duration: 614.553148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:43.183373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:42.502599Z","time spent":"680.767409ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T17:53:43.183395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:42.502533Z","time spent":"680.855981ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T17:53:43.183399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.351917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T17:53:43.183409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:42.568585Z","time spent":"614.815582ms","remote":"127.0.0.1:45666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c4fcb5355bad02\" "} {"level":"info","ts":"2026-07-23T17:53:43.183448Z","caller":"traceutil/trace.go:171","msg":"trace[55345419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2177; }","duration":"178.440037ms","start":"2026-07-23T17:53:43.004994Z","end":"2026-07-23T17:53:43.183434Z","steps":["trace[55345419] 'agreement among raft nodes before linearized reading' (duration: 178.346916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:43.183472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.495329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-23T17:53:43.183408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:42.651188Z","time spent":"532.204022ms","remote":"127.0.0.1:46018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-07-23T17:53:43.183494Z","caller":"traceutil/trace.go:171","msg":"trace[1960920098] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2177; }","duration":"464.56331ms","start":"2026-07-23T17:53:42.718924Z","end":"2026-07-23T17:53:43.183487Z","steps":["trace[1960920098] 'agreement among raft nodes before linearized reading' (duration: 464.517939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:43.183512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:42.718904Z","time spent":"464.60329ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-23T17:53:43.183544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.661973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-07-23T17:53:43.183576Z","caller":"traceutil/trace.go:171","msg":"trace[1999119359] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2177; }","duration":"271.729225ms","start":"2026-07-23T17:53:42.911837Z","end":"2026-07-23T17:53:43.183566Z","steps":["trace[1999119359] 'agreement among raft nodes before linearized reading' (duration: 271.596802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:43.183713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.595562ms","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-07-23T17:53:43.183748Z","caller":"traceutil/trace.go:171","msg":"trace[13106729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2177; }","duration":"681.673103ms","start":"2026-07-23T17:53:42.502065Z","end":"2026-07-23T17:53:43.183738Z","steps":["trace[13106729] 'agreement among raft nodes before linearized reading' (duration: 681.535501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:43.183775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:42.502039Z","time spent":"681.729104ms","remote":"127.0.0.1:45870","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-07-23T17:53:43.183816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.173607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-23T17:53:43.183842Z","caller":"traceutil/trace.go:171","msg":"trace[693686936] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2177; }","duration":"159.256818ms","start":"2026-07-23T17:53:43.024577Z","end":"2026-07-23T17:53:43.183833Z","steps":["trace[693686936] 'agreement among raft nodes before linearized reading' (duration: 159.182977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:43.946937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.030045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T17:53:43.947352Z","caller":"traceutil/trace.go:171","msg":"trace[1115025718] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"758.624248ms","start":"2026-07-23T17:53:43.188708Z","end":"2026-07-23T17:53:43.947332Z","steps":["trace[1115025718] 'process raft request' (duration: 758.521196ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:43.947339Z","caller":"traceutil/trace.go:171","msg":"trace[461681000] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"758.610807ms","start":"2026-07-23T17:53:43.188692Z","end":"2026-07-23T17:53:43.947303Z","steps":["trace[461681000] 'process raft request' (duration: 758.494235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:43.947438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:43.188693Z","time spent":"758.707529ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T17:53:43.947547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:43.188685Z","time spent":"758.735639ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:53:43.947597Z","caller":"traceutil/trace.go:171","msg":"trace[599787842] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"706.728478ms","start":"2026-07-23T17:53:43.240855Z","end":"2026-07-23T17:53:43.947583Z","steps":["trace[599787842] 'process raft request' (duration: 706.419434ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:53:43.947589Z","caller":"traceutil/trace.go:171","msg":"trace[1143834312] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2258; }","duration":"755.054634ms","start":"2026-07-23T17:53:43.192504Z","end":"2026-07-23T17:53:43.947558Z","steps":["trace[1143834312] 'read index received' (duration: 47.096768ms)","trace[1143834312] 'applied index is now lower than readState.Index' (duration: 707.954406ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:53:43.947652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:43.24083Z","time spent":"706.79098ms","remote":"127.0.0.1:45666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:53:43.947703Z","caller":"traceutil/trace.go:171","msg":"trace[1193630460] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"760.397753ms","start":"2026-07-23T17:53:43.18729Z","end":"2026-07-23T17:53:43.947688Z","steps":["trace[1193630460] 'process raft request' (duration: 628.541986ms)","trace[1193630460] 'compare' (duration: 130.71779ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:53:43.947806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:43.187272Z","time spent":"760.464373ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:53:43.948615Z","caller":"traceutil/trace.go:171","msg":"trace[67784506] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"760.724079ms","start":"2026-07-23T17:53:43.187865Z","end":"2026-07-23T17:53:43.948589Z","steps":["trace[67784506] 'process raft request' (duration: 759.230516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:43.948725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:43.187855Z","time spent":"760.80909ms","remote":"127.0.0.1:45870","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-07-23T17:53:43.954459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.949766ms","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-07-23T17:53:43.955077Z","caller":"traceutil/trace.go:171","msg":"trace[936137024] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2182; }","duration":"762.594145ms","start":"2026-07-23T17:53:43.192469Z","end":"2026-07-23T17:53:43.955063Z","steps":["trace[936137024] 'agreement among raft nodes before linearized reading' (duration: 755.179196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:53:43.955121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:53:43.192452Z","time spent":"762.656495ms","remote":"127.0.0.1:46042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-23T17:55:04.203515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.405433ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-23T17:55:04.203606Z","caller":"traceutil/trace.go:171","msg":"trace[1065922307] linearizableReadLoop","detail":"{readStateIndex:2826; appliedIndex:2825; }","duration":"198.522225ms","start":"2026-07-23T17:55:04.005069Z","end":"2026-07-23T17:55:04.203591Z","steps":["trace[1065922307] 'read index received' (duration: 24.138332ms)","trace[1065922307] 'applied index is now lower than readState.Index' (duration: 174.382743ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:55:04.203767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.755132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c4fcc5dc64d8d4\" ","response":"range_response_count:1 size:779"} {"level":"warn","ts":"2026-07-23T17:55:04.203747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.68005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:55:04.203793Z","caller":"traceutil/trace.go:171","msg":"trace[37576200] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c4fcc5dc64d8d4; range_end:; response_count:1; response_revision:2725; }","duration":"119.825594ms","start":"2026-07-23T17:55:04.08396Z","end":"2026-07-23T17:55:04.203786Z","steps":["trace[37576200] 'agreement among raft nodes before linearized reading' (duration: 119.69961ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:55:04.203823Z","caller":"traceutil/trace.go:171","msg":"trace[1594796711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2725; }","duration":"198.797204ms","start":"2026-07-23T17:55:04.005007Z","end":"2026-07-23T17:55:04.203804Z","steps":["trace[1594796711] 'agreement among raft nodes before linearized reading' (duration: 198.630429ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:55:04.407749Z","caller":"traceutil/trace.go:171","msg":"trace[436577010] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"200.657571ms","start":"2026-07-23T17:55:04.207077Z","end":"2026-07-23T17:55:04.407734Z","steps":["trace[436577010] 'process raft request' (duration: 200.554748ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:55:04.509785Z","caller":"traceutil/trace.go:171","msg":"trace[1713384753] linearizableReadLoop","detail":"{readStateIndex:2830; appliedIndex:2826; }","duration":"103.462262ms","start":"2026-07-23T17:55:04.406298Z","end":"2026-07-23T17:55:04.50976Z","steps":["trace[1713384753] 'read index received' (duration: 1.385182ms)","trace[1713384753] 'applied index is now lower than readState.Index' (duration: 102.07617ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:55:04.509844Z","caller":"traceutil/trace.go:171","msg":"trace[391665657] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"158.470424ms","start":"2026-07-23T17:55:04.351331Z","end":"2026-07-23T17:55:04.509802Z","steps":["trace[391665657] 'process raft request' (duration: 158.349091ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:55:04.50987Z","caller":"traceutil/trace.go:171","msg":"trace[682793977] transaction","detail":"{read_only:false; response_revision:2727; number_of_response:1; }","duration":"176.266441ms","start":"2026-07-23T17:55:04.333568Z","end":"2026-07-23T17:55:04.509835Z","steps":["trace[682793977] 'process raft request' (duration: 171.904477ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:55:04.509924Z","caller":"traceutil/trace.go:171","msg":"trace[1764353089] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"158.357951ms","start":"2026-07-23T17:55:04.351524Z","end":"2026-07-23T17:55:04.509882Z","steps":["trace[1764353089] 'process raft request' (duration: 158.198856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:55:04.510312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.989908ms","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-07-23T17:55:04.510446Z","caller":"traceutil/trace.go:171","msg":"trace[1948901292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2729; }","duration":"104.105741ms","start":"2026-07-23T17:55:04.406262Z","end":"2026-07-23T17:55:04.510367Z","steps":["trace[1948901292] 'agreement among raft nodes before linearized reading' (duration: 103.863384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:42.822596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.09498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:60261"} {"level":"info","ts":"2026-07-23T17:58:42.822826Z","caller":"traceutil/trace.go:171","msg":"trace[520105703] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4577; }","duration":"106.375754ms","start":"2026-07-23T17:58:42.716429Z","end":"2026-07-23T17:58:42.822805Z","steps":["trace[520105703] 'range keys from in-memory index tree' (duration: 105.779424ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:43.60319Z","caller":"traceutil/trace.go:171","msg":"trace[1708087348] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"287.492301ms","start":"2026-07-23T17:58:43.315678Z","end":"2026-07-23T17:58:43.60317Z","steps":["trace[1708087348] 'process raft request' (duration: 287.333948ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:43.606457Z","caller":"traceutil/trace.go:171","msg":"trace[251585733] transaction","detail":"{read_only:false; response_revision:4582; number_of_response:1; }","duration":"250.850549ms","start":"2026-07-23T17:58:43.355587Z","end":"2026-07-23T17:58:43.606438Z","steps":["trace[251585733] 'process raft request' (duration: 250.673146ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:43.606516Z","caller":"traceutil/trace.go:171","msg":"trace[615286692] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"250.95001ms","start":"2026-07-23T17:58:43.355466Z","end":"2026-07-23T17:58:43.606416Z","steps":["trace[615286692] 'process raft request' (duration: 250.719557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:49.489182Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870945165962607,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T17:58:49.738007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.46316ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:58:49.738165Z","caller":"traceutil/trace.go:171","msg":"trace[1283710264] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4661; }","duration":"133.637023ms","start":"2026-07-23T17:58:49.604508Z","end":"2026-07-23T17:58:49.738145Z","steps":["trace[1283710264] 'range keys from in-memory index tree' (duration: 133.44617ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:49.739029Z","caller":"traceutil/trace.go:171","msg":"trace[393450856] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"821.562517ms","start":"2026-07-23T17:58:48.917424Z","end":"2026-07-23T17:58:49.738987Z","steps":["trace[393450856] 'process raft request' (duration: 762.737732ms)","trace[393450856] 'compare' (duration: 58.038772ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:58:49.739188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:48.917404Z","time spent":"821.70582ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:58:49.744018Z","caller":"traceutil/trace.go:171","msg":"trace[815343279] linearizableReadLoop","detail":"{readStateIndex:4838; appliedIndex:4836; }","duration":"755.491848ms","start":"2026-07-23T17:58:48.988504Z","end":"2026-07-23T17:58:49.743996Z","steps":["trace[815343279] 'read index received' (duration: 691.673557ms)","trace[815343279] 'applied index is now lower than readState.Index' (duration: 63.816761ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:58:49.744195Z","caller":"traceutil/trace.go:171","msg":"trace[1383476184] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"113.748314ms","start":"2026-07-23T17:58:49.630428Z","end":"2026-07-23T17:58:49.744176Z","steps":["trace[1383476184] 'process raft request' (duration: 113.369907ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:49.744336Z","caller":"traceutil/trace.go:171","msg":"trace[454461621] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"826.750636ms","start":"2026-07-23T17:58:48.917576Z","end":"2026-07-23T17:58:49.744327Z","steps":["trace[454461621] 'process raft request' (duration: 825.991854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:49.744429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:48.917553Z","time spent":"826.820607ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":952,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T17:58:49.744472Z","caller":"traceutil/trace.go:171","msg":"trace[1773119273] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"110.67321ms","start":"2026-07-23T17:58:49.633781Z","end":"2026-07-23T17:58:49.744454Z","steps":["trace[1773119273] 'process raft request' (duration: 110.156882ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:49.744184Z","caller":"traceutil/trace.go:171","msg":"trace[1246697548] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"114.76631ms","start":"2026-07-23T17:58:49.629392Z","end":"2026-07-23T17:58:49.744159Z","steps":["trace[1246697548] 'process raft request' (duration: 114.324083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:49.745822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.889163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:58:49.74586Z","caller":"traceutil/trace.go:171","msg":"trace[610351010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4666; }","duration":"738.969085ms","start":"2026-07-23T17:58:49.006882Z","end":"2026-07-23T17:58:49.745852Z","steps":["trace[610351010] 'agreement among raft nodes before linearized reading' (duration: 737.978997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:49.745882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:49.006866Z","time spent":"739.011605ms","remote":"127.0.0.1:45576","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-07-23T17:58:49.746311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.800217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-07-23T17:58:49.746365Z","caller":"traceutil/trace.go:171","msg":"trace[1859530370] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4666; }","duration":"757.902999ms","start":"2026-07-23T17:58:48.988448Z","end":"2026-07-23T17:58:49.746351Z","steps":["trace[1859530370] 'agreement among raft nodes before linearized reading' (duration: 756.325002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:49.746402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:48.988426Z","time spent":"757.96556ms","remote":"127.0.0.1:45698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2207440,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-07-23T17:58:49.746429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.150497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12974"} {"level":"info","ts":"2026-07-23T17:58:49.746468Z","caller":"traceutil/trace.go:171","msg":"trace[1808833099] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4666; }","duration":"132.214649ms","start":"2026-07-23T17:58:49.614243Z","end":"2026-07-23T17:58:49.746458Z","steps":["trace[1808833099] 'agreement among raft nodes before linearized reading' (duration: 132.128547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:49.746626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.706017ms","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-07-23T17:58:49.746662Z","caller":"traceutil/trace.go:171","msg":"trace[1558814819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4666; }","duration":"108.769738ms","start":"2026-07-23T17:58:49.637876Z","end":"2026-07-23T17:58:49.746645Z","steps":["trace[1558814819] 'agreement among raft nodes before linearized reading' (duration: 108.705117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:49.746753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.511303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5b995d9676-mjm9w.18c4fcffdee1c42b\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-07-23T17:58:49.746774Z","caller":"traceutil/trace.go:171","msg":"trace[1274038896] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5b995d9676-mjm9w.18c4fcffdee1c42b; range_end:; response_count:1; response_revision:4666; }","duration":"129.558903ms","start":"2026-07-23T17:58:49.61721Z","end":"2026-07-23T17:58:49.746769Z","steps":["trace[1274038896] 'agreement among raft nodes before linearized reading' (duration: 129.500932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:49.747603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.464227ms","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-07-23T17:58:49.7477Z","caller":"traceutil/trace.go:171","msg":"trace[978882728] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4666; }","duration":"360.612509ms","start":"2026-07-23T17:58:49.387072Z","end":"2026-07-23T17:58:49.747684Z","steps":["trace[978882728] 'agreement among raft nodes before linearized reading' (duration: 360.456107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:49.749181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:49.38705Z","time spent":"362.107456ms","remote":"127.0.0.1:46012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-23T17:58:49.905149Z","caller":"traceutil/trace.go:171","msg":"trace[2001366741] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"115.84645ms","start":"2026-07-23T17:58:49.78926Z","end":"2026-07-23T17:58:49.905107Z","steps":["trace[2001366741] 'process raft request' (duration: 81.347649ms)","trace[2001366741] 'compare' (duration: 34.249786ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:58:49.905409Z","caller":"traceutil/trace.go:171","msg":"trace[674067048] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"115.025674ms","start":"2026-07-23T17:58:49.79031Z","end":"2026-07-23T17:58:49.905335Z","steps":["trace[674067048] 'process raft request' (duration: 114.716809ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:49.905577Z","caller":"traceutil/trace.go:171","msg":"trace[1444635799] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"108.981442ms","start":"2026-07-23T17:58:49.796574Z","end":"2026-07-23T17:58:49.905555Z","steps":["trace[1444635799] 'process raft request' (duration: 108.606976ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:49.905791Z","caller":"traceutil/trace.go:171","msg":"trace[314954309] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"112.40199ms","start":"2026-07-23T17:58:49.793277Z","end":"2026-07-23T17:58:49.905679Z","steps":["trace[314954309] 'process raft request' (duration: 111.80173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:49.905868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.461065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9318"} {"level":"info","ts":"2026-07-23T17:58:49.90595Z","caller":"traceutil/trace.go:171","msg":"trace[806369916] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4679; }","duration":"111.580696ms","start":"2026-07-23T17:58:49.794351Z","end":"2026-07-23T17:58:49.905932Z","steps":["trace[806369916] 'agreement among raft nodes before linearized reading' (duration: 111.303982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:50.435083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.36269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-23T17:58:50.435341Z","caller":"traceutil/trace.go:171","msg":"trace[1773660811] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"506.415032ms","start":"2026-07-23T17:58:49.928891Z","end":"2026-07-23T17:58:50.435306Z","steps":["trace[1773660811] 'process raft request' (duration: 305.761898ms)","trace[1773660811] 'compare' (duration: 199.84723ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:58:50.435463Z","caller":"traceutil/trace.go:171","msg":"trace[1353451184] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"201.329265ms","start":"2026-07-23T17:58:50.234115Z","end":"2026-07-23T17:58:50.435444Z","steps":["trace[1353451184] 'process raft request' (duration: 201.274904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:50.435488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:49.92887Z","time spent":"506.545154ms","remote":"127.0.0.1:45666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T17:58:50.435755Z","caller":"traceutil/trace.go:171","msg":"trace[1426816820] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"506.539254ms","start":"2026-07-23T17:58:49.929205Z","end":"2026-07-23T17:58:50.435744Z","steps":["trace[1426816820] 'process raft request' (duration: 505.978475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:50.435834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:49.929195Z","time spent":"506.607205ms","remote":"127.0.0.1:45796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2565,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T17:58:50.435824Z","caller":"traceutil/trace.go:171","msg":"trace[997294444] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"505.789662ms","start":"2026-07-23T17:58:49.930004Z","end":"2026-07-23T17:58:50.435793Z","steps":["trace[997294444] 'process raft request' (duration: 505.264863ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:50.435999Z","caller":"traceutil/trace.go:171","msg":"trace[1781083192] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"474.540217ms","start":"2026-07-23T17:58:49.961449Z","end":"2026-07-23T17:58:50.435989Z","steps":["trace[1781083192] 'process raft request' (duration: 473.904626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:50.436007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:49.929988Z","time spent":"505.907713ms","remote":"127.0.0.1:46044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9560,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T17:58:50.436063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:49.961411Z","time spent":"474.625229ms","remote":"127.0.0.1:45666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T17:58:50.436035Z","caller":"traceutil/trace.go:171","msg":"trace[1320641708] linearizableReadLoop","detail":"{readStateIndex:4863; appliedIndex:4860; }","duration":"478.861231ms","start":"2026-07-23T17:58:49.957124Z","end":"2026-07-23T17:58:50.435986Z","steps":["trace[1320641708] 'read index received' (duration: 277.545586ms)","trace[1320641708] 'applied index is now lower than readState.Index' (duration: 201.312155ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:58:50.436523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.711745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"warn","ts":"2026-07-23T17:58:50.436544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.420584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-23T17:58:50.436578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.863816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1455"} {"level":"info","ts":"2026-07-23T17:58:50.436585Z","caller":"traceutil/trace.go:171","msg":"trace[801089504] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4690; }","duration":"510.804617ms","start":"2026-07-23T17:58:49.925756Z","end":"2026-07-23T17:58:50.436561Z","steps":["trace[801089504] 'agreement among raft nodes before linearized reading' (duration: 510.545882ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:50.436591Z","caller":"traceutil/trace.go:171","msg":"trace[1616502003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4690; }","duration":"365.506806ms","start":"2026-07-23T17:58:50.071071Z","end":"2026-07-23T17:58:50.436578Z","steps":["trace[1616502003] 'agreement among raft nodes before linearized reading' (duration: 365.355273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:50.436586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.589796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18c4fd00439d8da3\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-07-23T17:58:50.436621Z","caller":"traceutil/trace.go:171","msg":"trace[1579315442] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4690; }","duration":"473.929417ms","start":"2026-07-23T17:58:49.96268Z","end":"2026-07-23T17:58:50.43661Z","steps":["trace[1579315442] 'agreement among raft nodes before linearized reading' (duration: 473.763455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:50.436633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:50.071052Z","time spent":"365.573397ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T17:58:50.436641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:49.925746Z","time spent":"510.881768ms","remote":"127.0.0.1:45684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1364,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-07-23T17:58:50.436654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:49.962665Z","time spent":"473.983478ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":1477,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-07-23T17:58:50.436654Z","caller":"traceutil/trace.go:171","msg":"trace[1612137406] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18c4fd00439d8da3; range_end:; response_count:1; response_revision:4690; }","duration":"475.703168ms","start":"2026-07-23T17:58:49.96093Z","end":"2026-07-23T17:58:50.436633Z","steps":["trace[1612137406] 'agreement among raft nodes before linearized reading' (duration: 475.498804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:50.436707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:49.960905Z","time spent":"475.789529ms","remote":"127.0.0.1:45666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":1034,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18c4fd00439d8da3\" "} {"level":"warn","ts":"2026-07-23T17:58:50.711603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.605618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-07-23T17:58:50.711689Z","caller":"traceutil/trace.go:171","msg":"trace[1549315016] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4694; }","duration":"174.734611ms","start":"2026-07-23T17:58:50.536931Z","end":"2026-07-23T17:58:50.711665Z","steps":["trace[1549315016] 'range keys from in-memory index tree' (duration: 174.451386ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:50.712638Z","caller":"traceutil/trace.go:171","msg":"trace[105610025] transaction","detail":"{read_only:false; response_revision:4695; number_of_response:1; }","duration":"175.557846ms","start":"2026-07-23T17:58:50.537065Z","end":"2026-07-23T17:58:50.712623Z","steps":["trace[105610025] 'process raft request' (duration: 172.816089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:50.712833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.588952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-07-23T17:58:50.712876Z","caller":"traceutil/trace.go:171","msg":"trace[1012943494] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4695; }","duration":"166.655494ms","start":"2026-07-23T17:58:50.54621Z","end":"2026-07-23T17:58:50.712865Z","steps":["trace[1012943494] 'agreement among raft nodes before linearized reading' (duration: 166.533362ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:50.712423Z","caller":"traceutil/trace.go:171","msg":"trace[686955423] linearizableReadLoop","detail":"{readStateIndex:4870; appliedIndex:4869; }","duration":"166.175296ms","start":"2026-07-23T17:58:50.546234Z","end":"2026-07-23T17:58:50.712409Z","steps":["trace[686955423] 'read index received' (duration: 163.586901ms)","trace[686955423] 'applied index is now lower than readState.Index' (duration: 2.587444ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:58:52.677676Z","caller":"traceutil/trace.go:171","msg":"trace[659269760] linearizableReadLoop","detail":"{readStateIndex:4899; appliedIndex:4898; }","duration":"140.034323ms","start":"2026-07-23T17:58:52.53762Z","end":"2026-07-23T17:58:52.677655Z","steps":["trace[659269760] 'read index received' (duration: 139.8771ms)","trace[659269760] 'applied index is now lower than readState.Index' (duration: 156.033µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:58:52.677767Z","caller":"traceutil/trace.go:171","msg":"trace[730357076] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"233.748077ms","start":"2026-07-23T17:58:52.443994Z","end":"2026-07-23T17:58:52.677742Z","steps":["trace[730357076] 'process raft request' (duration: 233.509403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:52.677888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.245876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T17:58:52.677939Z","caller":"traceutil/trace.go:171","msg":"trace[215625131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4724; }","duration":"140.348428ms","start":"2026-07-23T17:58:52.53758Z","end":"2026-07-23T17:58:52.677929Z","steps":["trace[215625131] 'agreement among raft nodes before linearized reading' (duration: 140.194456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:52.678052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.644201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0a585bcf-64a7-4244-bf60-d006ae5edd11\" ","response":"range_response_count:1 size:3939"} {"level":"info","ts":"2026-07-23T17:58:52.678087Z","caller":"traceutil/trace.go:171","msg":"trace[2082166209] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0a585bcf-64a7-4244-bf60-d006ae5edd11; range_end:; response_count:1; response_revision:4724; }","duration":"134.709833ms","start":"2026-07-23T17:58:52.543369Z","end":"2026-07-23T17:58:52.678079Z","steps":["trace[2082166209] 'agreement among raft nodes before linearized reading' (duration: 134.566871ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:52.848524Z","caller":"traceutil/trace.go:171","msg":"trace[1478882966] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"167.030242ms","start":"2026-07-23T17:58:52.681462Z","end":"2026-07-23T17:58:52.848493Z","steps":["trace[1478882966] 'process raft request' (duration: 166.816988ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:57.929246Z","caller":"traceutil/trace.go:171","msg":"trace[1378707117] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"156.439746ms","start":"2026-07-23T17:58:57.772781Z","end":"2026-07-23T17:58:57.929221Z","steps":["trace[1378707117] 'process raft request' (duration: 156.265673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:58.118232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.080959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T17:58:58.118411Z","caller":"traceutil/trace.go:171","msg":"trace[1627067592] linearizableReadLoop","detail":"{readStateIndex:4987; appliedIndex:4984; }","duration":"293.973389ms","start":"2026-07-23T17:58:57.824421Z","end":"2026-07-23T17:58:58.118395Z","steps":["trace[1627067592] 'read index received' (duration: 104.624479ms)","trace[1627067592] 'applied index is now lower than readState.Index' (duration: 189.3477ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:58:58.118522Z","caller":"traceutil/trace.go:171","msg":"trace[1009090576] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"344.427133ms","start":"2026-07-23T17:58:57.773979Z","end":"2026-07-23T17:58:58.118407Z","steps":["trace[1009090576] 'process raft request' (duration: 205.0881ms)","trace[1009090576] 'compare' (duration: 138.980567ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:58:58.118731Z","caller":"traceutil/trace.go:171","msg":"trace[1387421339] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"343.116181ms","start":"2026-07-23T17:58:57.775582Z","end":"2026-07-23T17:58:58.118699Z","steps":["trace[1387421339] 'process raft request' (duration: 342.759914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:58.118771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:57.773964Z","time spent":"344.659668ms","remote":"127.0.0.1:45870","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-07-23T17:58:58.118898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:58:57.775575Z","time spent":"343.234703ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T17:58:58.118893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.37666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:58:58.118967Z","caller":"traceutil/trace.go:171","msg":"trace[714795876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4808; }","duration":"112.519041ms","start":"2026-07-23T17:58:58.006429Z","end":"2026-07-23T17:58:58.118948Z","steps":["trace[714795876] 'agreement among raft nodes before linearized reading' (duration: 112.355128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:58.118959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.457117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-23T17:58:58.119041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.250737ms","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-07-23T17:58:58.119113Z","caller":"traceutil/trace.go:171","msg":"trace[1784049344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4808; }","duration":"290.37823ms","start":"2026-07-23T17:58:57.828716Z","end":"2026-07-23T17:58:58.119094Z","steps":["trace[1784049344] 'agreement among raft nodes before linearized reading' (duration: 290.125395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:58.119232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.732546ms","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-07-23T17:58:58.119278Z","caller":"traceutil/trace.go:171","msg":"trace[1333466822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4808; }","duration":"293.794876ms","start":"2026-07-23T17:58:57.825471Z","end":"2026-07-23T17:58:58.119266Z","steps":["trace[1333466822] 'agreement among raft nodes before linearized reading' (duration: 293.589203ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:58:58.119098Z","caller":"traceutil/trace.go:171","msg":"trace[785023650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4808; }","duration":"294.62723ms","start":"2026-07-23T17:58:57.824456Z","end":"2026-07-23T17:58:58.119084Z","steps":["trace[785023650] 'agreement among raft nodes before linearized reading' (duration: 294.288085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:58:58.121472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.648492ms","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-07-23T17:58:58.121656Z","caller":"traceutil/trace.go:171","msg":"trace[458093109] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4808; }","duration":"297.250615ms","start":"2026-07-23T17:58:57.824378Z","end":"2026-07-23T17:58:58.121629Z","steps":["trace[458093109] 'agreement among raft nodes before linearized reading' (duration: 294.342126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:03.275982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870945165963035,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T17:59:03.428413Z","caller":"traceutil/trace.go:171","msg":"trace[203817715] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"659.965596ms","start":"2026-07-23T17:59:02.768419Z","end":"2026-07-23T17:59:03.428385Z","steps":["trace[203817715] 'process raft request' (duration: 659.778123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:03.428675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:02.768401Z","time spent":"660.098798ms","remote":"127.0.0.1:45796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:59:03.454934Z","caller":"traceutil/trace.go:171","msg":"trace[313770265] linearizableReadLoop","detail":"{readStateIndex:5030; appliedIndex:5028; }","duration":"679.382711ms","start":"2026-07-23T17:59:02.775527Z","end":"2026-07-23T17:59:03.45491Z","steps":["trace[313770265] 'read index received' (duration: 652.617952ms)","trace[313770265] 'applied index is now lower than readState.Index' (duration: 26.763759ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:59:03.455085Z","caller":"traceutil/trace.go:171","msg":"trace[1223024887] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"349.901114ms","start":"2026-07-23T17:59:03.10513Z","end":"2026-07-23T17:59:03.455031Z","steps":["trace[1223024887] 'process raft request' (duration: 349.646259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:03.455196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.630005ms","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-07-23T17:59:03.455286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:03.105114Z","time spent":"350.060008ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:59:03.455329Z","caller":"traceutil/trace.go:171","msg":"trace[1851730226] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4850; }","duration":"679.811978ms","start":"2026-07-23T17:59:02.775491Z","end":"2026-07-23T17:59:03.455303Z","steps":["trace[1851730226] 'agreement among raft nodes before linearized reading' (duration: 679.558923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:03.455518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:02.775475Z","time spent":"680.028331ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-07-23T17:59:03.643928Z","caller":"traceutil/trace.go:171","msg":"trace[2034336221] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"206.797032ms","start":"2026-07-23T17:59:03.437094Z","end":"2026-07-23T17:59:03.643891Z","steps":["trace[2034336221] 'process raft request' (duration: 118.877986ms)","trace[2034336221] 'compare' (duration: 87.711853ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:59:03.644016Z","caller":"traceutil/trace.go:171","msg":"trace[1339273347] linearizableReadLoop","detail":"{readStateIndex:5034; appliedIndex:5030; }","duration":"188.984383ms","start":"2026-07-23T17:59:03.455024Z","end":"2026-07-23T17:59:03.644009Z","steps":["trace[1339273347] 'read index received' (duration: 100.934084ms)","trace[1339273347] 'applied index is now lower than readState.Index' (duration: 88.049699ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:59:03.644102Z","caller":"traceutil/trace.go:171","msg":"trace[1048330179] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"206.870703ms","start":"2026-07-23T17:59:03.437225Z","end":"2026-07-23T17:59:03.644095Z","steps":["trace[1048330179] 'process raft request' (duration: 206.67264ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:03.643949Z","caller":"traceutil/trace.go:171","msg":"trace[1832885863] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"206.810663ms","start":"2026-07-23T17:59:03.437117Z","end":"2026-07-23T17:59:03.643928Z","steps":["trace[1832885863] 'process raft request' (duration: 206.747782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:03.644239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.798264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:59:03.644276Z","caller":"traceutil/trace.go:171","msg":"trace[1608914803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4854; }","duration":"638.857386ms","start":"2026-07-23T17:59:03.005396Z","end":"2026-07-23T17:59:03.644253Z","steps":["trace[1608914803] 'agreement among raft nodes before linearized reading' (duration: 638.807025ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:03.643997Z","caller":"traceutil/trace.go:171","msg":"trace[661577561] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"206.824472ms","start":"2026-07-23T17:59:03.437139Z","end":"2026-07-23T17:59:03.643964Z","steps":["trace[661577561] 'process raft request' (duration: 206.66281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:03.6443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:03.005384Z","time spent":"638.910777ms","remote":"127.0.0.1:45576","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-07-23T17:59:03.644329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.803471ms","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-07-23T17:59:03.644377Z","caller":"traceutil/trace.go:171","msg":"trace[516634144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4854; }","duration":"749.901383ms","start":"2026-07-23T17:59:02.894465Z","end":"2026-07-23T17:59:03.644366Z","steps":["trace[516634144] 'agreement among raft nodes before linearized reading' (duration: 749.75092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:03.644412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:02.894446Z","time spent":"749.957773ms","remote":"127.0.0.1:45870","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-07-23T17:59:03.668409Z","caller":"traceutil/trace.go:171","msg":"trace[1527350516] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"174.46785ms","start":"2026-07-23T17:59:03.493906Z","end":"2026-07-23T17:59:03.668373Z","steps":["trace[1527350516] 'process raft request' (duration: 174.317847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:03.669172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.610666ms","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":"warn","ts":"2026-07-23T17:59:03.669175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.79081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-07-23T17:59:03.669323Z","caller":"traceutil/trace.go:171","msg":"trace[690825752] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4855; }","duration":"110.984944ms","start":"2026-07-23T17:59:03.558308Z","end":"2026-07-23T17:59:03.669293Z","steps":["trace[690825752] 'agreement among raft nodes before linearized reading' (duration: 110.625688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:03.669325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.425573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0a585bcf-64a7-4244-bf60-d006ae5edd11\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-23T17:59:03.669331Z","caller":"traceutil/trace.go:171","msg":"trace[338711448] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:4855; }","duration":"154.735478ms","start":"2026-07-23T17:59:03.514494Z","end":"2026-07-23T17:59:03.66923Z","steps":["trace[338711448] 'agreement among raft nodes before linearized reading' (duration: 154.538104ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:03.669393Z","caller":"traceutil/trace.go:171","msg":"trace[524770188] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0a585bcf-64a7-4244-bf60-d006ae5edd11; range_end:; response_count:1; response_revision:4855; }","duration":"210.546105ms","start":"2026-07-23T17:59:03.458825Z","end":"2026-07-23T17:59:03.669371Z","steps":["trace[524770188] 'agreement among raft nodes before linearized reading' (duration: 210.325391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:04.21632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.02163ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-23T17:59:04.216663Z","caller":"traceutil/trace.go:171","msg":"trace[1317461217] linearizableReadLoop","detail":"{readStateIndex:5037; appliedIndex:5036; }","duration":"493.661954ms","start":"2026-07-23T17:59:03.722967Z","end":"2026-07-23T17:59:04.216629Z","steps":["trace[1317461217] 'read index received' (duration: 44.61µs)","trace[1317461217] 'applied index is now lower than readState.Index' (duration: 493.610214ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:59:04.216958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.97612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5b995d9676-mjm9w\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-07-23T17:59:04.217055Z","caller":"traceutil/trace.go:171","msg":"trace[14858895] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-5b995d9676-mjm9w; range_end:; response_count:1; response_revision:4856; }","duration":"494.125142ms","start":"2026-07-23T17:59:03.722914Z","end":"2026-07-23T17:59:04.217039Z","steps":["trace[14858895] 'agreement among raft nodes before linearized reading' (duration: 493.863437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:04.217106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:03.722891Z","time spent":"494.198624ms","remote":"127.0.0.1:45796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11870,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5b995d9676-mjm9w\" "} {"level":"info","ts":"2026-07-23T17:59:04.217448Z","caller":"traceutil/trace.go:171","msg":"trace[746863847] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"268.063347ms","start":"2026-07-23T17:59:03.949357Z","end":"2026-07-23T17:59:04.21742Z","steps":["trace[746863847] 'process raft request' (duration: 267.582888ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:04.285241Z","caller":"traceutil/trace.go:171","msg":"trace[1484398253] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"145.49758ms","start":"2026-07-23T17:59:04.139723Z","end":"2026-07-23T17:59:04.28522Z","steps":["trace[1484398253] 'process raft request' (duration: 145.355107ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:04.570479Z","caller":"traceutil/trace.go:171","msg":"trace[793785247] linearizableReadLoop","detail":"{readStateIndex:5040; appliedIndex:5038; }","duration":"353.713992ms","start":"2026-07-23T17:59:04.216742Z","end":"2026-07-23T17:59:04.570456Z","steps":["trace[793785247] 'read index received' (duration: 68.451428ms)","trace[793785247] 'applied index is now lower than readState.Index' (duration: 285.261494ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:59:04.570604Z","caller":"traceutil/trace.go:171","msg":"trace[280219853] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"430.798755ms","start":"2026-07-23T17:59:04.139796Z","end":"2026-07-23T17:59:04.570594Z","steps":["trace[280219853] 'process raft request' (duration: 430.427269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:04.570917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.505634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:59:04.57099Z","caller":"traceutil/trace.go:171","msg":"trace[1484216733] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:4860; }","duration":"836.640827ms","start":"2026-07-23T17:59:03.734333Z","end":"2026-07-23T17:59:04.570974Z","steps":["trace[1484216733] 'agreement among raft nodes before linearized reading' (duration: 836.507185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:04.571014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.759093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T17:59:04.571047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:03.734311Z","time spent":"836.720678ms","remote":"127.0.0.1:45736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-07-23T17:59:04.571064Z","caller":"traceutil/trace.go:171","msg":"trace[1802984289] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4860; }","duration":"266.842285ms","start":"2026-07-23T17:59:04.304203Z","end":"2026-07-23T17:59:04.571045Z","steps":["trace[1802984289] 'agreement among raft nodes before linearized reading' (duration: 266.768214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:04.57105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.168366ms","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-07-23T17:59:04.571234Z","caller":"traceutil/trace.go:171","msg":"trace[585525275] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"341.229123ms","start":"2026-07-23T17:59:04.229965Z","end":"2026-07-23T17:59:04.571195Z","steps":["trace[585525275] 'process raft request' (duration: 340.405019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:04.570923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:04.13976Z","time spent":"431.054109ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T17:59:04.571263Z","caller":"traceutil/trace.go:171","msg":"trace[1461282993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4860; }","duration":"411.43589ms","start":"2026-07-23T17:59:04.159808Z","end":"2026-07-23T17:59:04.571244Z","steps":["trace[1461282993] 'agreement among raft nodes before linearized reading' (duration: 410.955282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:04.571416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:04.229945Z","time spent":"341.364965ms","remote":"127.0.0.1:45796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11655,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T17:59:04.571336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.554496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T17:59:04.571472Z","caller":"traceutil/trace.go:171","msg":"trace[443077829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4860; }","duration":"416.726399ms","start":"2026-07-23T17:59:04.154734Z","end":"2026-07-23T17:59:04.57146Z","steps":["trace[443077829] 'agreement among raft nodes before linearized reading' (duration: 416.398864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:04.571528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:04.159789Z","time spent":"411.624493ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T17:59:04.571575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.826216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T17:59:04.571613Z","caller":"traceutil/trace.go:171","msg":"trace[1458035580] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:4860; }","duration":"563.900866ms","start":"2026-07-23T17:59:04.007702Z","end":"2026-07-23T17:59:04.571603Z","steps":["trace[1458035580] 'agreement among raft nodes before linearized reading' (duration: 563.832315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:04.571652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:04.00768Z","time spent":"563.963178ms","remote":"127.0.0.1:45984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-07-23T17:59:04.571735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.55161ms","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-07-23T17:59:04.571641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:04.154715Z","time spent":"416.78471ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-23T17:59:04.571808Z","caller":"traceutil/trace.go:171","msg":"trace[161865539] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4860; }","duration":"797.673342ms","start":"2026-07-23T17:59:03.774098Z","end":"2026-07-23T17:59:04.571771Z","steps":["trace[161865539] 'agreement among raft nodes before linearized reading' (duration: 797.079552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:04.571877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:03.774074Z","time spent":"797.791524ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-23T17:59:04.571907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.161966ms","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-07-23T17:59:04.571945Z","caller":"traceutil/trace.go:171","msg":"trace[2068066027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4860; }","duration":"417.230398ms","start":"2026-07-23T17:59:04.154705Z","end":"2026-07-23T17:59:04.571935Z","steps":["trace[2068066027] 'agreement among raft nodes before linearized reading' (duration: 417.115596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:04.571977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:04.154691Z","time spent":"417.277928ms","remote":"127.0.0.1:45870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T17:59:04.572572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.818937ms","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-07-23T17:59:04.573078Z","caller":"traceutil/trace.go:171","msg":"trace[1355374319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4860; }","duration":"424.362567ms","start":"2026-07-23T17:59:04.148695Z","end":"2026-07-23T17:59:04.573058Z","steps":["trace[1355374319] 'agreement among raft nodes before linearized reading' (duration: 422.016148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:04.573208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:04.148673Z","time spent":"424.518259ms","remote":"127.0.0.1:45870","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-07-23T17:59:04.801519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.421501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T17:59:04.801638Z","caller":"traceutil/trace.go:171","msg":"trace[1973206280] linearizableReadLoop","detail":"{readStateIndex:5042; appliedIndex:5041; }","duration":"223.628151ms","start":"2026-07-23T17:59:04.577991Z","end":"2026-07-23T17:59:04.801619Z","steps":["trace[1973206280] 'read index received' (duration: 79.053166ms)","trace[1973206280] 'applied index is now lower than readState.Index' (duration: 144.573685ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:59:04.801773Z","caller":"traceutil/trace.go:171","msg":"trace[709303949] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"224.332292ms","start":"2026-07-23T17:59:04.577377Z","end":"2026-07-23T17:59:04.801709Z","steps":["trace[709303949] 'process raft request' (duration: 79.623846ms)","trace[709303949] 'compare' (duration: 144.259239ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:59:04.802336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.315712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16402"} {"level":"info","ts":"2026-07-23T17:59:04.802781Z","caller":"traceutil/trace.go:171","msg":"trace[782642721] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4861; }","duration":"224.776481ms","start":"2026-07-23T17:59:04.577965Z","end":"2026-07-23T17:59:04.802742Z","steps":["trace[782642721] 'agreement among raft nodes before linearized reading' (duration: 224.063408ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:04.803706Z","caller":"traceutil/trace.go:171","msg":"trace[1846399516] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"225.536133ms","start":"2026-07-23T17:59:04.57814Z","end":"2026-07-23T17:59:04.803676Z","steps":["trace[1846399516] 'process raft request' (duration: 225.3438ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:04.80376Z","caller":"traceutil/trace.go:171","msg":"trace[1414679763] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"224.223791ms","start":"2026-07-23T17:59:04.579521Z","end":"2026-07-23T17:59:04.803744Z","steps":["trace[1414679763] 'process raft request' (duration: 224.059398ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:04.804074Z","caller":"traceutil/trace.go:171","msg":"trace[541221565] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"224.494894ms","start":"2026-07-23T17:59:04.579551Z","end":"2026-07-23T17:59:04.804046Z","steps":["trace[541221565] 'process raft request' (duration: 224.131829ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:04.804437Z","caller":"traceutil/trace.go:171","msg":"trace[554535947] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"224.80525ms","start":"2026-07-23T17:59:04.579618Z","end":"2026-07-23T17:59:04.804423Z","steps":["trace[554535947] 'process raft request' (duration: 224.340052ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:04.80483Z","caller":"traceutil/trace.go:171","msg":"trace[1742935184] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"222.298868ms","start":"2026-07-23T17:59:04.582516Z","end":"2026-07-23T17:59:04.804815Z","steps":["trace[1742935184] 'process raft request' (duration: 221.984803ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:04.804856Z","caller":"traceutil/trace.go:171","msg":"trace[1755771318] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"224.700379ms","start":"2026-07-23T17:59:04.580143Z","end":"2026-07-23T17:59:04.804843Z","steps":["trace[1755771318] 'process raft request' (duration: 224.253681ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:04.864046Z","caller":"traceutil/trace.go:171","msg":"trace[1984407799] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"138.9531ms","start":"2026-07-23T17:59:04.725057Z","end":"2026-07-23T17:59:04.86401Z","steps":["trace[1984407799] 'process raft request' (duration: 138.747946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:04.86419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.080508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0a585bcf-64a7-4244-bf60-d006ae5edd11\" ","response":"range_response_count:1 size:1322"} {"level":"warn","ts":"2026-07-23T17:59:04.864227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.476956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-07-23T17:59:04.864295Z","caller":"traceutil/trace.go:171","msg":"trace[1671243986] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4868; }","duration":"112.611839ms","start":"2026-07-23T17:59:04.751668Z","end":"2026-07-23T17:59:04.86428Z","steps":["trace[1671243986] 'agreement among raft nodes before linearized reading' (duration: 112.352594ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:04.864297Z","caller":"traceutil/trace.go:171","msg":"trace[21808527] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0a585bcf-64a7-4244-bf60-d006ae5edd11; range_end:; response_count:1; response_revision:4868; }","duration":"286.218611ms","start":"2026-07-23T17:59:04.578051Z","end":"2026-07-23T17:59:04.86427Z","steps":["trace[21808527] 'agreement among raft nodes before linearized reading' (duration: 285.901055ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:05.038096Z","caller":"traceutil/trace.go:171","msg":"trace[140684584] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"222.062494ms","start":"2026-07-23T17:59:04.816009Z","end":"2026-07-23T17:59:05.038072Z","steps":["trace[140684584] 'process raft request' (duration: 128.242911ms)","trace[140684584] 'compare' (duration: 93.596678ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:59:05.038421Z","caller":"traceutil/trace.go:171","msg":"trace[670464141] linearizableReadLoop","detail":"{readStateIndex:5051; appliedIndex:5049; }","duration":"169.764777ms","start":"2026-07-23T17:59:04.868619Z","end":"2026-07-23T17:59:05.038384Z","steps":["trace[670464141] 'read index received' (duration: 75.611078ms)","trace[670464141] 'applied index is now lower than readState.Index' (duration: 94.152469ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:59:05.038554Z","caller":"traceutil/trace.go:171","msg":"trace[884585619] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"221.397163ms","start":"2026-07-23T17:59:04.817144Z","end":"2026-07-23T17:59:05.038541Z","steps":["trace[884585619] 'process raft request' (duration: 220.846723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:05.038758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.120513ms","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-07-23T17:59:05.038817Z","caller":"traceutil/trace.go:171","msg":"trace[820962374] 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:4870; }","duration":"170.209784ms","start":"2026-07-23T17:59:04.86859Z","end":"2026-07-23T17:59:05.038799Z","steps":["trace[820962374] 'agreement among raft nodes before linearized reading' (duration: 170.059141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:05.038803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.212219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:59:05.038921Z","caller":"traceutil/trace.go:171","msg":"trace[1001723853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4870; }","duration":"125.343651ms","start":"2026-07-23T17:59:04.913557Z","end":"2026-07-23T17:59:05.038901Z","steps":["trace[1001723853] 'agreement among raft nodes before linearized reading' (duration: 125.149038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:05.038957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.788174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1321"} {"level":"info","ts":"2026-07-23T17:59:05.039116Z","caller":"traceutil/trace.go:171","msg":"trace[847836214] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4870; }","duration":"168.100749ms","start":"2026-07-23T17:59:04.870995Z","end":"2026-07-23T17:59:05.039096Z","steps":["trace[847836214] 'agreement among raft nodes before linearized reading' (duration: 167.663121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:05.761124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.587612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T17:59:05.761226Z","caller":"traceutil/trace.go:171","msg":"trace[1193555736] linearizableReadLoop","detail":"{readStateIndex:5052; appliedIndex:5051; }","duration":"699.191383ms","start":"2026-07-23T17:59:05.062019Z","end":"2026-07-23T17:59:05.76121Z","steps":["trace[1193555736] 'read index received' (duration: 132.423078ms)","trace[1193555736] 'applied index is now lower than readState.Index' (duration: 566.767205ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T17:59:05.761316Z","caller":"traceutil/trace.go:171","msg":"trace[1887964121] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"701.518441ms","start":"2026-07-23T17:59:05.059786Z","end":"2026-07-23T17:59:05.761305Z","steps":["trace[1887964121] 'process raft request' (duration: 134.660925ms)","trace[1887964121] 'compare' (duration: 565.92052ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T17:59:05.761393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:05.059775Z","time spent":"701.572803ms","remote":"127.0.0.1:45666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T17:59:05.761908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.887494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-07-23T17:59:05.761964Z","caller":"traceutil/trace.go:171","msg":"trace[355485257] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4871; }","duration":"699.967625ms","start":"2026-07-23T17:59:05.061981Z","end":"2026-07-23T17:59:05.761948Z","steps":["trace[355485257] 'agreement among raft nodes before linearized reading' (duration: 699.792032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:05.762005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T17:59:05.061963Z","time spent":"700.031907ms","remote":"127.0.0.1:46044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9661,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"info","ts":"2026-07-23T17:59:44.157026Z","caller":"traceutil/trace.go:171","msg":"trace[1068905227] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"131.237802ms","start":"2026-07-23T17:59:44.025747Z","end":"2026-07-23T17:59:44.156985Z","steps":["trace[1068905227] 'process raft request' (duration: 130.781825ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:44.280354Z","caller":"traceutil/trace.go:171","msg":"trace[1966675466] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"100.507587ms","start":"2026-07-23T17:59:44.179813Z","end":"2026-07-23T17:59:44.280321Z","steps":["trace[1966675466] 'process raft request' (duration: 93.613606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T17:59:44.753966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.681689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T17:59:44.754128Z","caller":"traceutil/trace.go:171","msg":"trace[463781961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5206; }","duration":"103.913102ms","start":"2026-07-23T17:59:44.650185Z","end":"2026-07-23T17:59:44.754098Z","steps":["trace[463781961] 'range keys from in-memory index tree' (duration: 103.588948ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T17:59:58.096672Z","caller":"traceutil/trace.go:171","msg":"trace[523372668] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"150.757759ms","start":"2026-07-23T17:59:57.945886Z","end":"2026-07-23T17:59:58.096644Z","steps":["trace[523372668] 'process raft request' (duration: 150.204831ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:00:35.01169Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2892} {"level":"info","ts":"2026-07-23T18:00:35.111571Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2892,"took":"98.367504ms","hash":3641505499,"current-db-size-bytes":54222848,"current-db-size":"54 MB","current-db-size-in-use-bytes":29495296,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-23T18:00:35.111652Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3641505499,"revision":2892,"compact-revision":-1} {"level":"info","ts":"2026-07-23T18:02:05.721367Z","caller":"traceutil/trace.go:171","msg":"trace[821580820] transaction","detail":"{read_only:false; response_revision:6076; number_of_response:1; }","duration":"103.074533ms","start":"2026-07-23T18:02:05.618264Z","end":"2026-07-23T18:02:05.721338Z","steps":["trace[821580820] 'process raft request' (duration: 102.913201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:02:06.107393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.312075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T18:02:06.108449Z","caller":"traceutil/trace.go:171","msg":"trace[567640503] transaction","detail":"{read_only:false; response_revision:6079; number_of_response:1; }","duration":"209.989692ms","start":"2026-07-23T18:02:05.898361Z","end":"2026-07-23T18:02:06.108351Z","steps":["trace[567640503] 'process raft request' (duration: 95.613582ms)","trace[567640503] 'compare' (duration: 113.162552ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T18:02:06.109811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.576736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-23T18:02:06.109918Z","caller":"traceutil/trace.go:171","msg":"trace[266928237] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6080; }","duration":"209.707477ms","start":"2026-07-23T18:02:05.90019Z","end":"2026-07-23T18:02:06.109898Z","steps":["trace[266928237] 'agreement among raft nodes before linearized reading' (duration: 208.859855ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:02:06.109841Z","caller":"traceutil/trace.go:171","msg":"trace[1482777259] linearizableReadLoop","detail":"{readStateIndex:6302; appliedIndex:6300; }","duration":"208.671852ms","start":"2026-07-23T18:02:05.900221Z","end":"2026-07-23T18:02:06.108893Z","steps":["trace[1482777259] 'read index received' (duration: 93.795275ms)","trace[1482777259] 'applied index is now lower than readState.Index' (duration: 114.456551ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:02:06.110032Z","caller":"traceutil/trace.go:171","msg":"trace[1080086895] transaction","detail":"{read_only:false; response_revision:6080; number_of_response:1; }","duration":"210.354777ms","start":"2026-07-23T18:02:05.898546Z","end":"2026-07-23T18:02:06.108901Z","steps":["trace[1080086895] 'process raft request' (duration: 208.945387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:02:06.116994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.828248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:02:06.117117Z","caller":"traceutil/trace.go:171","msg":"trace[1161954350] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:6081; }","duration":"213.95529ms","start":"2026-07-23T18:02:05.903113Z","end":"2026-07-23T18:02:06.117069Z","steps":["trace[1161954350] 'agreement among raft nodes before linearized reading' (duration: 213.776678ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:02:12.100072Z","caller":"traceutil/trace.go:171","msg":"trace[451289299] transaction","detail":"{read_only:false; response_revision:6133; number_of_response:1; }","duration":"108.359688ms","start":"2026-07-23T18:02:11.991686Z","end":"2026-07-23T18:02:12.100046Z","steps":["trace[451289299] 'process raft request' (duration: 108.143634ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:02:12.297108Z","caller":"traceutil/trace.go:171","msg":"trace[1302818825] linearizableReadLoop","detail":"{readStateIndex:6357; appliedIndex:6356; }","duration":"288.676122ms","start":"2026-07-23T18:02:12.008386Z","end":"2026-07-23T18:02:12.297062Z","steps":["trace[1302818825] 'read index received' (duration: 91.808612ms)","trace[1302818825] 'applied index is now lower than readState.Index' (duration: 196.866ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T18:02:12.297275Z","caller":"traceutil/trace.go:171","msg":"trace[270631630] transaction","detail":"{read_only:false; response_revision:6134; number_of_response:1; }","duration":"304.771642ms","start":"2026-07-23T18:02:11.992486Z","end":"2026-07-23T18:02:12.297258Z","steps":["trace[270631630] 'process raft request' (duration: 297.852608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:02:12.297401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T18:02:11.992468Z","time spent":"304.846152ms","remote":"127.0.0.1:45870","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-07-23T18:02:12.297461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.538455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-bootstrap-cm57n\" ","response":"range_response_count:1 size:8620"} {"level":"info","ts":"2026-07-23T18:02:12.297558Z","caller":"traceutil/trace.go:171","msg":"trace[122562664] range","detail":"{range_begin:/registry/pods/openstack/keystone-bootstrap-cm57n; range_end:; response_count:1; response_revision:6134; }","duration":"231.694098ms","start":"2026-07-23T18:02:12.065836Z","end":"2026-07-23T18:02:12.29753Z","steps":["trace[122562664] 'agreement among raft nodes before linearized reading' (duration: 231.455874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:02:12.297856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.467184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T18:02:12.297909Z","caller":"traceutil/trace.go:171","msg":"trace[1905322779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6134; }","duration":"289.579516ms","start":"2026-07-23T18:02:12.008313Z","end":"2026-07-23T18:02:12.297893Z","steps":["trace[1905322779] 'agreement among raft nodes before linearized reading' (duration: 289.502325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:02:12.298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.081454ms","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-07-23T18:02:12.29807Z","caller":"traceutil/trace.go:171","msg":"trace[1968366384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6134; }","duration":"160.173876ms","start":"2026-07-23T18:02:12.137878Z","end":"2026-07-23T18:02:12.298052Z","steps":["trace[1968366384] 'agreement among raft nodes before linearized reading' (duration: 159.987123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T18:02:12.298214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.353118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-23T18:02:12.29827Z","caller":"traceutil/trace.go:171","msg":"trace[1597436741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6134; }","duration":"160.44831ms","start":"2026-07-23T18:02:12.137808Z","end":"2026-07-23T18:02:12.298257Z","steps":["trace[1597436741] 'agreement among raft nodes before linearized reading' (duration: 160.301058ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T18:05:35.019459Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5573} {"level":"info","ts":"2026-07-23T18:05:35.124646Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5573,"took":"103.492401ms","hash":209339717,"current-db-size-bytes":54222848,"current-db-size":"54 MB","current-db-size-in-use-bytes":18333696,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-23T18:05:35.124713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":209339717,"revision":5573,"compact-revision":2892}