{"level":"warn","ts":"2026-05-05T12:50:03.476111Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T12:50:03.476291Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T12:50:03.476419Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T12:50:03.476476Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-05-05T12:50:03.476537Z","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-05-05T12:50:03.477364Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-05-05T12:50:03.47753Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T12:50:03.482092Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.171807ms"} {"level":"info","ts":"2026-05-05T12:50:03.48982Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-05-05T12:50:03.489986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T12:50:03.490032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-05-05T12:50:03.490058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T12:50:03.490099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-05-05T12:50:03.490175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-05-05T12:50:03.495297Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T12:50:03.496722Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T12:50:03.497919Z","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-05-05T12:50:03.500163Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T12:50:03.500768Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T12:50:03.500851Z","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-05-05T12:50:03.501811Z","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-05-05T12:50:03.501866Z","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-05-05T12:50:03.502658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-05-05T12:50:03.502816Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-05-05T12:50:03.505339Z","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-05-05T12:50:03.505444Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-05-05T12:50:03.505489Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-05-05T12:50:03.505705Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T12:50:03.50575Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T12:50:04.390466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T12:50:04.390538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T12:50:04.390583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-05-05T12:50:04.390605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-05-05T12:50:04.390623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-05-05T12:50:04.39064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-05-05T12:50:04.390655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-05-05T12:50:04.392228Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T12:50:04.392231Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:50:04.392231Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:50:04.392293Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:50:04.392535Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T12:50:04.3926Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T12:50:04.393233Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:50:04.393322Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:50:04.39336Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:50:04.394333Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-05-05T12:50:04.395736Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T12:50:33.382404Z","caller":"traceutil/trace.go:171","msg":"trace[1512901823] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"212.02075ms","start":"2026-05-05T12:50:33.170338Z","end":"2026-05-05T12:50:33.382359Z","steps":["trace[1512901823] 'process raft request' (duration: 113.945373ms)","trace[1512901823] 'compare' (duration: 97.672287ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:33.382628Z","caller":"traceutil/trace.go:171","msg":"trace[1367054469] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"210.234884ms","start":"2026-05-05T12:50:33.172368Z","end":"2026-05-05T12:50:33.382603Z","steps":["trace[1367054469] 'process raft request' (duration: 210.116321ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:33.382836Z","caller":"traceutil/trace.go:171","msg":"trace[1772374990] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"212.41971ms","start":"2026-05-05T12:50:33.170407Z","end":"2026-05-05T12:50:33.382826Z","steps":["trace[1772374990] 'process raft request' (duration: 211.685832ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:33.383018Z","caller":"traceutil/trace.go:171","msg":"trace[844822989] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"206.95517ms","start":"2026-05-05T12:50:33.176039Z","end":"2026-05-05T12:50:33.382994Z","steps":["trace[844822989] 'process raft request' (duration: 206.619522ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:33.383097Z","caller":"traceutil/trace.go:171","msg":"trace[1391661003] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"200.828594ms","start":"2026-05-05T12:50:33.182219Z","end":"2026-05-05T12:50:33.383048Z","steps":["trace[1391661003] 'process raft request' (duration: 200.525346ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:33.383162Z","caller":"traceutil/trace.go:171","msg":"trace[966241019] transaction","detail":"{read_only:false; number_of_response:1; response_revision:480; }","duration":"207.166196ms","start":"2026-05-05T12:50:33.175984Z","end":"2026-05-05T12:50:33.38315Z","steps":["trace[966241019] 'process raft request' (duration: 206.561501ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:33.383175Z","caller":"traceutil/trace.go:171","msg":"trace[952430318] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"193.787383ms","start":"2026-05-05T12:50:33.189374Z","end":"2026-05-05T12:50:33.383162Z","steps":["trace[952430318] 'process raft request' (duration: 193.512607ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:33.383216Z","caller":"traceutil/trace.go:171","msg":"trace[527590082] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"192.975232ms","start":"2026-05-05T12:50:33.190223Z","end":"2026-05-05T12:50:33.383199Z","steps":["trace[527590082] 'process raft request' (duration: 192.818238ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:33.383097Z","caller":"traceutil/trace.go:171","msg":"trace[1883424706] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"194.765218ms","start":"2026-05-05T12:50:33.188294Z","end":"2026-05-05T12:50:33.383059Z","steps":["trace[1883424706] 'process raft request' (duration: 194.522472ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:33.383281Z","caller":"traceutil/trace.go:171","msg":"trace[563287691] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"189.75541ms","start":"2026-05-05T12:50:33.193516Z","end":"2026-05-05T12:50:33.383272Z","steps":["trace[563287691] 'process raft request' (duration: 189.602816ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:33.383285Z","caller":"traceutil/trace.go:171","msg":"trace[1477496009] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:495; }","duration":"128.259298ms","start":"2026-05-05T12:50:33.255007Z","end":"2026-05-05T12:50:33.383266Z","steps":["trace[1477496009] 'read index received' (duration: 29.34808ms)","trace[1477496009] 'applied index is now lower than readState.Index' (duration: 98.910298ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:33.38335Z","caller":"traceutil/trace.go:171","msg":"trace[822974411] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"167.275336ms","start":"2026-05-05T12:50:33.216054Z","end":"2026-05-05T12:50:33.383329Z","steps":["trace[822974411] 'process raft request' (duration: 167.163683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:33.383413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.390602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-05T12:50:33.383478Z","caller":"traceutil/trace.go:171","msg":"trace[1169897731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:487; }","duration":"128.512065ms","start":"2026-05-05T12:50:33.254956Z","end":"2026-05-05T12:50:33.383468Z","steps":["trace[1169897731] 'agreement among raft nodes before linearized reading' (duration: 128.367321ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:39.549425Z","caller":"traceutil/trace.go:171","msg":"trace[1749156645] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"143.744936ms","start":"2026-05-05T12:50:39.405654Z","end":"2026-05-05T12:50:39.549399Z","steps":["trace[1749156645] 'read index received' (duration: 142.971786ms)","trace[1749156645] 'applied index is now lower than readState.Index' (duration: 772.19µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:39.549477Z","caller":"traceutil/trace.go:171","msg":"trace[754709569] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"264.784951ms","start":"2026-05-05T12:50:39.284666Z","end":"2026-05-05T12:50:39.549451Z","steps":["trace[754709569] 'process raft request' (duration: 264.229916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:39.549633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.961541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wt4z5\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-05T12:50:39.549684Z","caller":"traceutil/trace.go:171","msg":"trace[114634745] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wt4z5; range_end:; response_count:1; response_revision:573; }","duration":"144.055964ms","start":"2026-05-05T12:50:39.405609Z","end":"2026-05-05T12:50:39.549665Z","steps":["trace[114634745] 'agreement among raft nodes before linearized reading' (duration: 143.92324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:39.549739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.689204ms","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-05-05T12:50:39.549841Z","caller":"traceutil/trace.go:171","msg":"trace[333686418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"134.842108ms","start":"2026-05-05T12:50:39.414973Z","end":"2026-05-05T12:50:39.549815Z","steps":["trace[333686418] 'agreement among raft nodes before linearized reading' (duration: 134.631062ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:39.750061Z","caller":"traceutil/trace.go:171","msg":"trace[723296772] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"185.093352ms","start":"2026-05-05T12:50:39.564929Z","end":"2026-05-05T12:50:39.750023Z","steps":["trace[723296772] 'process raft request' (duration: 184.838015ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:39.772533Z","caller":"traceutil/trace.go:171","msg":"trace[1713082414] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"169.81344ms","start":"2026-05-05T12:50:39.602691Z","end":"2026-05-05T12:50:39.772504Z","steps":["trace[1713082414] 'process raft request' (duration: 169.692627ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:40.243132Z","caller":"traceutil/trace.go:171","msg":"trace[350049448] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"444.833812ms","start":"2026-05-05T12:50:39.798268Z","end":"2026-05-05T12:50:40.243101Z","steps":["trace[350049448] 'process raft request' (duration: 444.682498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:40.243912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:39.79825Z","time spent":"444.985537ms","remote":"127.0.0.1:33812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4542,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:50:40.366934Z","caller":"traceutil/trace.go:171","msg":"trace[881057902] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"553.731205ms","start":"2026-05-05T12:50:39.813176Z","end":"2026-05-05T12:50:40.366907Z","steps":["trace[881057902] 'process raft request' (duration: 553.408717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:40.367835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:39.813156Z","time spent":"553.842059ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:50:40.370816Z","caller":"traceutil/trace.go:171","msg":"trace[4617695] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"290.098697ms","start":"2026-05-05T12:50:40.080664Z","end":"2026-05-05T12:50:40.370762Z","steps":["trace[4617695] 'read index received' (duration: 162.31883ms)","trace[4617695] 'applied index is now lower than readState.Index' (duration: 127.762907ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:40.371058Z","caller":"traceutil/trace.go:171","msg":"trace[747818096] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"203.016682ms","start":"2026-05-05T12:50:40.167914Z","end":"2026-05-05T12:50:40.370931Z","steps":["trace[747818096] 'process raft request' (duration: 199.101451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:40.371586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.891107ms","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-05-05T12:50:40.371782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.887111ms","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-05-05T12:50:40.371829Z","caller":"traceutil/trace.go:171","msg":"trace[2146449298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"291.110682ms","start":"2026-05-05T12:50:40.080606Z","end":"2026-05-05T12:50:40.371717Z","steps":["trace[2146449298] 'agreement among raft nodes before linearized reading' (duration: 290.327823ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:40.371908Z","caller":"traceutil/trace.go:171","msg":"trace[1277124934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"281.025815ms","start":"2026-05-05T12:50:40.090833Z","end":"2026-05-05T12:50:40.371859Z","steps":["trace[1277124934] 'agreement among raft nodes before linearized reading' (duration: 280.189544ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:08.054091Z","caller":"traceutil/trace.go:171","msg":"trace[721214433] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"157.418972ms","start":"2026-05-05T12:52:07.896641Z","end":"2026-05-05T12:52:08.05406Z","steps":["trace[721214433] 'process raft request' (duration: 157.200206ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:53.285782Z","caller":"traceutil/trace.go:171","msg":"trace[731399790] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2169; }","duration":"249.884765ms","start":"2026-05-05T12:52:53.035873Z","end":"2026-05-05T12:52:53.285758Z","steps":["trace[731399790] 'read index received' (duration: 249.71715ms)","trace[731399790] 'applied index is now lower than readState.Index' (duration: 166.425µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:53.28585Z","caller":"traceutil/trace.go:171","msg":"trace[488473297] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"318.784021ms","start":"2026-05-05T12:52:52.967029Z","end":"2026-05-05T12:52:53.285813Z","steps":["trace[488473297] 'process raft request' (duration: 318.575026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:53.286002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.120532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-05-05T12:52:53.286141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.177014ms","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-05-05T12:52:53.286202Z","caller":"traceutil/trace.go:171","msg":"trace[133159946] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2093; }","duration":"250.372578ms","start":"2026-05-05T12:52:53.035818Z","end":"2026-05-05T12:52:53.28619Z","steps":["trace[133159946] 'agreement among raft nodes before linearized reading' (duration: 250.076831ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:53.286224Z","caller":"traceutil/trace.go:171","msg":"trace[1430953313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2093; }","duration":"226.320138ms","start":"2026-05-05T12:52:53.059883Z","end":"2026-05-05T12:52:53.286203Z","steps":["trace[1430953313] 'agreement among raft nodes before linearized reading' (duration: 226.053921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:53.286198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.779352ms","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-05-05T12:52:53.286076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:52.966996Z","time spent":"318.948035ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:52:53.28628Z","caller":"traceutil/trace.go:171","msg":"trace[964559033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2093; }","duration":"208.922785ms","start":"2026-05-05T12:52:53.077339Z","end":"2026-05-05T12:52:53.286262Z","steps":["trace[964559033] 'agreement among raft nodes before linearized reading' (duration: 208.7096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:53.28609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.865977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T12:52:53.286367Z","caller":"traceutil/trace.go:171","msg":"trace[1113908050] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2093; }","duration":"136.206277ms","start":"2026-05-05T12:52:53.150144Z","end":"2026-05-05T12:52:53.28635Z","steps":["trace[1113908050] 'agreement among raft nodes before linearized reading' (duration: 135.818346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:53.286144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.999913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T12:52:53.286508Z","caller":"traceutil/trace.go:171","msg":"trace[521973209] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2093; }","duration":"153.387753ms","start":"2026-05-05T12:52:53.133083Z","end":"2026-05-05T12:52:53.286471Z","steps":["trace[521973209] 'agreement among raft nodes before linearized reading' (duration: 152.966772ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:53.524013Z","caller":"traceutil/trace.go:171","msg":"trace[1310695699] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"233.483901ms","start":"2026-05-05T12:52:53.290504Z","end":"2026-05-05T12:52:53.523988Z","steps":["trace[1310695699] 'process raft request' (duration: 169.732875ms)","trace[1310695699] 'compare' (duration: 63.345925ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:53.524321Z","caller":"traceutil/trace.go:171","msg":"trace[2044809135] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"233.600194ms","start":"2026-05-05T12:52:53.290691Z","end":"2026-05-05T12:52:53.524291Z","steps":["trace[2044809135] 'process raft request' (duration: 233.256655ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:53.524567Z","caller":"traceutil/trace.go:171","msg":"trace[282899512] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"233.604755ms","start":"2026-05-05T12:52:53.290948Z","end":"2026-05-05T12:52:53.524552Z","steps":["trace[282899512] 'process raft request' (duration: 233.255996ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:53.524603Z","caller":"traceutil/trace.go:171","msg":"trace[651975805] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"104.837558ms","start":"2026-05-05T12:52:53.419742Z","end":"2026-05-05T12:52:53.524579Z","steps":["trace[651975805] 'process raft request' (duration: 104.745275ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:20.910883Z","caller":"traceutil/trace.go:171","msg":"trace[2116019101] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"101.340433ms","start":"2026-05-05T12:54:20.809522Z","end":"2026-05-05T12:54:20.910862Z","steps":["trace[2116019101] 'process raft request' (duration: 101.23425ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:20.957706Z","caller":"traceutil/trace.go:171","msg":"trace[1851488325] transaction","detail":"{read_only:false; response_revision:2764; number_of_response:1; }","duration":"146.922801ms","start":"2026-05-05T12:54:20.810758Z","end":"2026-05-05T12:54:20.957681Z","steps":["trace[1851488325] 'process raft request' (duration: 146.657514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:58:09.804437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.842785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:720065"} {"level":"info","ts":"2026-05-05T12:58:09.804536Z","caller":"traceutil/trace.go:171","msg":"trace[358804720] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4887; }","duration":"120.957898ms","start":"2026-05-05T12:58:09.683559Z","end":"2026-05-05T12:58:09.804517Z","steps":["trace[358804720] 'range keys from in-memory index tree' (duration: 120.350241ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:58:11.971797Z","caller":"traceutil/trace.go:171","msg":"trace[82387432] linearizableReadLoop","detail":"{readStateIndex:5103; appliedIndex:5102; }","duration":"105.9131ms","start":"2026-05-05T12:58:11.865861Z","end":"2026-05-05T12:58:11.971775Z","steps":["trace[82387432] 'read index received' (duration: 105.740745ms)","trace[82387432] 'applied index is now lower than readState.Index' (duration: 171.395µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:58:11.971851Z","caller":"traceutil/trace.go:171","msg":"trace[1744611127] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"125.589877ms","start":"2026-05-05T12:58:11.846227Z","end":"2026-05-05T12:58:11.971817Z","steps":["trace[1744611127] 'process raft request' (duration: 125.419622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:58:11.971949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.077864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T12:58:11.972135Z","caller":"traceutil/trace.go:171","msg":"trace[1211444077] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4925; }","duration":"106.31654ms","start":"2026-05-05T12:58:11.865806Z","end":"2026-05-05T12:58:11.972123Z","steps":["trace[1211444077] 'agreement among raft nodes before linearized reading' (duration: 106.089904ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:58:12.098565Z","caller":"traceutil/trace.go:171","msg":"trace[1665913873] linearizableReadLoop","detail":"{readStateIndex:5104; appliedIndex:5103; }","duration":"103.389509ms","start":"2026-05-05T12:58:11.995153Z","end":"2026-05-05T12:58:12.098543Z","steps":["trace[1665913873] 'read index received' (duration: 93.283208ms)","trace[1665913873] 'applied index is now lower than readState.Index' (duration: 10.105151ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:58:12.098636Z","caller":"traceutil/trace.go:171","msg":"trace[1998391506] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"118.112999ms","start":"2026-05-05T12:58:11.980501Z","end":"2026-05-05T12:58:12.098614Z","steps":["trace[1998391506] 'process raft request' (duration: 107.950906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:58:12.099147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.975517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:778250"} {"level":"info","ts":"2026-05-05T12:58:12.099213Z","caller":"traceutil/trace.go:171","msg":"trace[1307773862] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4926; }","duration":"104.08514ms","start":"2026-05-05T12:58:11.995112Z","end":"2026-05-05T12:58:12.099197Z","steps":["trace[1307773862] 'agreement among raft nodes before linearized reading' (duration: 103.520553ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:58:20.99062Z","caller":"traceutil/trace.go:171","msg":"trace[512677390] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"170.887696ms","start":"2026-05-05T12:58:20.819697Z","end":"2026-05-05T12:58:20.990585Z","steps":["trace[512677390] 'process raft request' (duration: 170.715221ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:58:28.719114Z","caller":"traceutil/trace.go:171","msg":"trace[1525558231] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"138.452179ms","start":"2026-05-05T12:58:28.580633Z","end":"2026-05-05T12:58:28.719085Z","steps":["trace[1525558231] 'process raft request' (duration: 138.280865ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:00:04.418132Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3003} {"level":"info","ts":"2026-05-05T13:00:04.493264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3003,"took":"73.695271ms","hash":3472457330,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":31842304,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T13:00:04.493347Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3472457330,"revision":3003,"compact-revision":-1} {"level":"info","ts":"2026-05-05T13:04:20.01318Z","caller":"traceutil/trace.go:171","msg":"trace[769863249] transaction","detail":"{read_only:false; response_revision:8044; number_of_response:1; }","duration":"157.599608ms","start":"2026-05-05T13:04:19.855555Z","end":"2026-05-05T13:04:20.013155Z","steps":["trace[769863249] 'process raft request' (duration: 157.450544ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:04:20.135932Z","caller":"traceutil/trace.go:171","msg":"trace[988829553] linearizableReadLoop","detail":"{readStateIndex:8317; appliedIndex:8315; }","duration":"186.976794ms","start":"2026-05-05T13:04:19.948932Z","end":"2026-05-05T13:04:20.135909Z","steps":["trace[988829553] 'read index received' (duration: 64.132072ms)","trace[988829553] 'applied index is now lower than readState.Index' (duration: 122.843802ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:04:20.136002Z","caller":"traceutil/trace.go:171","msg":"trace[862248525] transaction","detail":"{read_only:false; response_revision:8045; number_of_response:1; }","duration":"189.884796ms","start":"2026-05-05T13:04:19.946095Z","end":"2026-05-05T13:04:20.13598Z","steps":["trace[862248525] 'process raft request' (duration: 188.302262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:04:20.136139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.187209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T13:04:20.136177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.527023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T13:04:20.136221Z","caller":"traceutil/trace.go:171","msg":"trace[1976223201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8045; }","duration":"174.598865ms","start":"2026-05-05T13:04:19.961609Z","end":"2026-05-05T13:04:20.136208Z","steps":["trace[1976223201] 'agreement among raft nodes before linearized reading' (duration: 174.449991ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:04:20.136218Z","caller":"traceutil/trace.go:171","msg":"trace[1890426715] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:8045; }","duration":"187.317943ms","start":"2026-05-05T13:04:19.948879Z","end":"2026-05-05T13:04:20.136197Z","steps":["trace[1890426715] 'agreement among raft nodes before linearized reading' (duration: 187.153408ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:04:32.943815Z","caller":"traceutil/trace.go:171","msg":"trace[2030493251] linearizableReadLoop","detail":"{readStateIndex:8384; appliedIndex:8383; }","duration":"140.808626ms","start":"2026-05-05T13:04:32.802977Z","end":"2026-05-05T13:04:32.943785Z","steps":["trace[2030493251] 'read index received' (duration: 140.657702ms)","trace[2030493251] 'applied index is now lower than readState.Index' (duration: 149.984µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:04:32.943874Z","caller":"traceutil/trace.go:171","msg":"trace[752326128] transaction","detail":"{read_only:false; response_revision:8109; number_of_response:1; }","duration":"168.757172ms","start":"2026-05-05T13:04:32.775082Z","end":"2026-05-05T13:04:32.943839Z","steps":["trace[752326128] 'process raft request' (duration: 168.514415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:04:32.944078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.080074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sr4kw\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-05T13:04:32.944129Z","caller":"traceutil/trace.go:171","msg":"trace[814393398] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sr4kw; range_end:; response_count:1; response_revision:8109; }","duration":"141.186356ms","start":"2026-05-05T13:04:32.802929Z","end":"2026-05-05T13:04:32.944115Z","steps":["trace[814393398] 'agreement among raft nodes before linearized reading' (duration: 140.9619ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:04:59.656899Z","caller":"traceutil/trace.go:171","msg":"trace[1438708144] linearizableReadLoop","detail":"{readStateIndex:8590; appliedIndex:8589; }","duration":"124.830716ms","start":"2026-05-05T13:04:59.532035Z","end":"2026-05-05T13:04:59.656866Z","steps":["trace[1438708144] 'read index received' (duration: 124.63214ms)","trace[1438708144] 'applied index is now lower than readState.Index' (duration: 197.726µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:04:59.656941Z","caller":"traceutil/trace.go:171","msg":"trace[1551071152] transaction","detail":"{read_only:false; response_revision:8307; number_of_response:1; }","duration":"132.640866ms","start":"2026-05-05T13:04:59.524267Z","end":"2026-05-05T13:04:59.656908Z","steps":["trace[1551071152] 'process raft request' (duration: 132.45003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:04:59.657088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.031321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-05T13:04:59.657131Z","caller":"traceutil/trace.go:171","msg":"trace[782740107] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8307; }","duration":"125.109474ms","start":"2026-05-05T13:04:59.532011Z","end":"2026-05-05T13:04:59.65712Z","steps":["trace[782740107] 'agreement among raft nodes before linearized reading' (duration: 124.96281ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:01.376925Z","caller":"traceutil/trace.go:171","msg":"trace[1435110099] transaction","detail":"{read_only:false; response_revision:8317; number_of_response:1; }","duration":"161.400855ms","start":"2026-05-05T13:05:01.215481Z","end":"2026-05-05T13:05:01.376882Z","steps":["trace[1435110099] 'process raft request' (duration: 161.191439ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:04.423714Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6136} {"level":"info","ts":"2026-05-05T13:05:04.522672Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6136,"took":"97.072964ms","hash":3349346999,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":22917120,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-05T13:05:04.522742Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3349346999,"revision":6136,"compact-revision":3003} {"level":"warn","ts":"2026-05-05T13:05:11.687661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.238407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:05:11.687728Z","caller":"traceutil/trace.go:171","msg":"trace[872773492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8411; }","duration":"141.36706ms","start":"2026-05-05T13:05:11.546346Z","end":"2026-05-05T13:05:11.687713Z","steps":["trace[872773492] 'range keys from in-memory index tree' (duration: 141.087862ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:12.272459Z","caller":"traceutil/trace.go:171","msg":"trace[21850604] linearizableReadLoop","detail":"{readStateIndex:8703; appliedIndex:8702; }","duration":"128.415966ms","start":"2026-05-05T13:05:12.144022Z","end":"2026-05-05T13:05:12.272438Z","steps":["trace[21850604] 'read index received' (duration: 122.106659ms)","trace[21850604] 'applied index is now lower than readState.Index' (duration: 6.308317ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:05:12.27253Z","caller":"traceutil/trace.go:171","msg":"trace[1470024512] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"220.88049ms","start":"2026-05-05T13:05:12.051616Z","end":"2026-05-05T13:05:12.272497Z","steps":["trace[1470024512] 'process raft request' (duration: 214.510271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:05:12.272579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.536149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:05:12.272605Z","caller":"traceutil/trace.go:171","msg":"trace[2042711844] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8414; }","duration":"128.617992ms","start":"2026-05-05T13:05:12.143979Z","end":"2026-05-05T13:05:12.272597Z","steps":["trace[2042711844] 'agreement among raft nodes before linearized reading' (duration: 128.54385ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:12.491448Z","caller":"traceutil/trace.go:171","msg":"trace[1528233082] linearizableReadLoop","detail":"{readStateIndex:8704; appliedIndex:8703; }","duration":"182.581861ms","start":"2026-05-05T13:05:12.308836Z","end":"2026-05-05T13:05:12.491418Z","steps":["trace[1528233082] 'read index received' (duration: 174.792531ms)","trace[1528233082] 'applied index is now lower than readState.Index' (duration: 7.78801ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:05:12.492048Z","caller":"traceutil/trace.go:171","msg":"trace[211322782] transaction","detail":"{read_only:false; response_revision:8415; number_of_response:1; }","duration":"319.921098ms","start":"2026-05-05T13:05:12.172091Z","end":"2026-05-05T13:05:12.492012Z","steps":["trace[211322782] 'process raft request' (duration: 311.46484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:05:12.492261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:05:12.172068Z","time spent":"320.101505ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:05:12.492291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.426386ms","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-05-05T13:05:12.492414Z","caller":"traceutil/trace.go:171","msg":"trace[665225669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8415; }","duration":"183.56396ms","start":"2026-05-05T13:05:12.308823Z","end":"2026-05-05T13:05:12.492386Z","steps":["trace[665225669] 'agreement among raft nodes before linearized reading' (duration: 183.158227ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:12.545661Z","caller":"traceutil/trace.go:171","msg":"trace[756223464] transaction","detail":"{read_only:false; response_revision:8416; number_of_response:1; }","duration":"190.068002ms","start":"2026-05-05T13:05:12.355568Z","end":"2026-05-05T13:05:12.545636Z","steps":["trace[756223464] 'process raft request' (duration: 189.838336ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:12.546011Z","caller":"traceutil/trace.go:171","msg":"trace[85388968] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"108.508805ms","start":"2026-05-05T13:05:12.437248Z","end":"2026-05-05T13:05:12.545757Z","steps":["trace[85388968] 'process raft request' (duration: 108.32467ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:13.14998Z","caller":"traceutil/trace.go:171","msg":"trace[727892453] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"121.658826ms","start":"2026-05-05T13:05:13.028271Z","end":"2026-05-05T13:05:13.14993Z","steps":["trace[727892453] 'process raft request' (duration: 121.480351ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:06:16.648077Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002480.wal"} {"level":"info","ts":"2026-05-05T13:07:33.842835Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T13:07:33.849164Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T13:07:33.849251Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T13:09:54.636336Z","caller":"traceutil/trace.go:171","msg":"trace[1959150078] linearizableReadLoop","detail":"{readStateIndex:11315; appliedIndex:11314; }","duration":"111.218255ms","start":"2026-05-05T13:09:54.525086Z","end":"2026-05-05T13:09:54.636305Z","steps":["trace[1959150078] 'read index received' (duration: 111.004009ms)","trace[1959150078] 'applied index is now lower than readState.Index' (duration: 212.786µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:09:54.636456Z","caller":"traceutil/trace.go:171","msg":"trace[295695553] transaction","detail":"{read_only:false; response_revision:10954; number_of_response:1; }","duration":"126.323512ms","start":"2026-05-05T13:09:54.510096Z","end":"2026-05-05T13:09:54.636419Z","steps":["trace[295695553] 'process raft request' (duration: 126.020654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:09:54.636504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.39293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:09:54.636571Z","caller":"traceutil/trace.go:171","msg":"trace[450166741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10954; }","duration":"111.477262ms","start":"2026-05-05T13:09:54.525078Z","end":"2026-05-05T13:09:54.636556Z","steps":["trace[450166741] 'agreement among raft nodes before linearized reading' (duration: 111.351828ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:10:04.432461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8333} {"level":"info","ts":"2026-05-05T13:10:04.504752Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8333,"took":"70.412942ms","hash":3977679588,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":26947584,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-05T13:10:04.504828Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3977679588,"revision":8333,"compact-revision":6136} {"level":"info","ts":"2026-05-05T13:14:54.780565Z","caller":"traceutil/trace.go:171","msg":"trace[68572259] linearizableReadLoop","detail":"{readStateIndex:13416; appliedIndex:13415; }","duration":"314.013627ms","start":"2026-05-05T13:14:54.466525Z","end":"2026-05-05T13:14:54.780538Z","steps":["trace[68572259] 'read index received' (duration: 313.572854ms)","trace[68572259] 'applied index is now lower than readState.Index' (duration: 437.113µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:14:54.780731Z","caller":"traceutil/trace.go:171","msg":"trace[920994674] transaction","detail":"{read_only:false; response_revision:12986; number_of_response:1; }","duration":"494.574643ms","start":"2026-05-05T13:14:54.286133Z","end":"2026-05-05T13:14:54.780708Z","steps":["trace[920994674] 'process raft request' (duration: 494.017357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:14:54.780837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:14:54.286118Z","time spent":"494.663256ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:14:54.781183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.275152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T13:14:54.781216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.290056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:14:54.781249Z","caller":"traceutil/trace.go:171","msg":"trace[850502510] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:12986; }","duration":"293.388075ms","start":"2026-05-05T13:14:54.487847Z","end":"2026-05-05T13:14:54.781235Z","steps":["trace[850502510] 'agreement among raft nodes before linearized reading' (duration: 293.275042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:14:54.781236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.395959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:14:54.781282Z","caller":"traceutil/trace.go:171","msg":"trace[1770396260] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:12986; }","duration":"314.79195ms","start":"2026-05-05T13:14:54.466468Z","end":"2026-05-05T13:14:54.781259Z","steps":["trace[1770396260] 'agreement among raft nodes before linearized reading' (duration: 314.240295ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:14:54.781312Z","caller":"traceutil/trace.go:171","msg":"trace[1191925667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12986; }","duration":"256.470791ms","start":"2026-05-05T13:14:54.524817Z","end":"2026-05-05T13:14:54.781288Z","steps":["trace[1191925667] 'agreement among raft nodes before linearized reading' (duration: 256.331827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:14:54.781338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:14:54.466448Z","time spent":"314.875033ms","remote":"127.0.0.1:33810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":63,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-05T13:14:54.781213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.868276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:14:54.781708Z","caller":"traceutil/trace.go:171","msg":"trace[173504988] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:12986; }","duration":"220.310629ms","start":"2026-05-05T13:14:54.561318Z","end":"2026-05-05T13:14:54.781629Z","steps":["trace[173504988] 'agreement among raft nodes before linearized reading' (duration: 219.818825ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:14:54.947786Z","caller":"traceutil/trace.go:171","msg":"trace[1518759165] transaction","detail":"{read_only:false; response_revision:12987; number_of_response:1; }","duration":"109.760533ms","start":"2026-05-05T13:14:54.837997Z","end":"2026-05-05T13:14:54.947758Z","steps":["trace[1518759165] 'process raft request' (duration: 50.738514ms)","trace[1518759165] 'compare' (duration: 58.886715ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:14:56.572019Z","caller":"traceutil/trace.go:171","msg":"trace[1952774309] transaction","detail":"{read_only:false; response_revision:12996; number_of_response:1; }","duration":"122.795512ms","start":"2026-05-05T13:14:56.44919Z","end":"2026-05-05T13:14:56.571985Z","steps":["trace[1952774309] 'process raft request' (duration: 122.531745ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:14:57.574956Z","caller":"traceutil/trace.go:171","msg":"trace[997805889] linearizableReadLoop","detail":"{readStateIndex:13432; appliedIndex:13431; }","duration":"119.312223ms","start":"2026-05-05T13:14:57.455621Z","end":"2026-05-05T13:14:57.574933Z","steps":["trace[997805889] 'read index received' (duration: 119.083966ms)","trace[997805889] 'applied index is now lower than readState.Index' (duration: 227.387µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:14:57.575067Z","caller":"traceutil/trace.go:171","msg":"trace[1002816680] transaction","detail":"{read_only:false; response_revision:13002; number_of_response:1; }","duration":"146.900983ms","start":"2026-05-05T13:14:57.42811Z","end":"2026-05-05T13:14:57.575011Z","steps":["trace[1002816680] 'process raft request' (duration: 146.663516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:14:57.575185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.890364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sr4kw\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-05T13:14:57.57523Z","caller":"traceutil/trace.go:171","msg":"trace[407016394] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sr4kw; range_end:; response_count:1; response_revision:13002; }","duration":"111.954656ms","start":"2026-05-05T13:14:57.463265Z","end":"2026-05-05T13:14:57.575219Z","steps":["trace[407016394] 'agreement among raft nodes before linearized reading' (duration: 111.794181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:14:57.575484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.781895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T13:14:57.575582Z","caller":"traceutil/trace.go:171","msg":"trace[1432787371] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13002; }","duration":"102.927639ms","start":"2026-05-05T13:14:57.472632Z","end":"2026-05-05T13:14:57.57556Z","steps":["trace[1432787371] 'agreement among raft nodes before linearized reading' (duration: 102.640261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:14:57.576102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.473616ms","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-05-05T13:14:57.576157Z","caller":"traceutil/trace.go:171","msg":"trace[1012431340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13002; }","duration":"120.58437ms","start":"2026-05-05T13:14:57.455559Z","end":"2026-05-05T13:14:57.576144Z","steps":["trace[1012431340] 'agreement among raft nodes before linearized reading' (duration: 119.465418ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:14:58.834512Z","caller":"traceutil/trace.go:171","msg":"trace[897944351] transaction","detail":"{read_only:false; response_revision:13011; number_of_response:1; }","duration":"155.979981ms","start":"2026-05-05T13:14:58.678489Z","end":"2026-05-05T13:14:58.834469Z","steps":["trace[897944351] 'process raft request' (duration: 141.531972ms)","trace[897944351] 'compare' (duration: 13.898953ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:14:58.951211Z","caller":"traceutil/trace.go:171","msg":"trace[707361010] transaction","detail":"{read_only:false; response_revision:13013; number_of_response:1; }","duration":"109.774103ms","start":"2026-05-05T13:14:58.8414Z","end":"2026-05-05T13:14:58.951174Z","steps":["trace[707361010] 'process raft request' (duration: 96.935151ms)","trace[707361010] 'compare' (duration: 12.662128ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:14:59.144988Z","caller":"traceutil/trace.go:171","msg":"trace[434395990] transaction","detail":"{read_only:false; response_revision:13016; number_of_response:1; }","duration":"105.171284ms","start":"2026-05-05T13:14:59.039789Z","end":"2026-05-05T13:14:59.14496Z","steps":["trace[434395990] 'process raft request' (duration: 104.963457ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:14:59.28541Z","caller":"traceutil/trace.go:171","msg":"trace[1790075072] linearizableReadLoop","detail":"{readStateIndex:13448; appliedIndex:13447; }","duration":"109.431824ms","start":"2026-05-05T13:14:59.175951Z","end":"2026-05-05T13:14:59.285383Z","steps":["trace[1790075072] 'read index received' (duration: 58.331468ms)","trace[1790075072] 'applied index is now lower than readState.Index' (duration: 51.099306ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:14:59.28546Z","caller":"traceutil/trace.go:171","msg":"trace[607821540] transaction","detail":"{read_only:false; response_revision:13017; number_of_response:1; }","duration":"129.275905ms","start":"2026-05-05T13:14:59.156146Z","end":"2026-05-05T13:14:59.285422Z","steps":["trace[607821540] 'process raft request' (duration: 78.20326ms)","trace[607821540] 'compare' (duration: 50.88873ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:14:59.285681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.713252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-user\" ","response":"range_response_count:1 size:7251"} {"level":"info","ts":"2026-05-05T13:14:59.285756Z","caller":"traceutil/trace.go:171","msg":"trace[1993917735] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-user; range_end:; response_count:1; response_revision:13017; }","duration":"109.844205ms","start":"2026-05-05T13:14:59.175895Z","end":"2026-05-05T13:14:59.28574Z","steps":["trace[1993917735] 'agreement among raft nodes before linearized reading' (duration: 109.66018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:14:59.285797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.013982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T13:14:59.285832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.247639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-service\" ","response":"range_response_count:1 size:6483"} {"level":"info","ts":"2026-05-05T13:14:59.285874Z","caller":"traceutil/trace.go:171","msg":"trace[677090388] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-endpoints; range_end:; response_count:0; response_revision:13017; }","duration":"109.142746ms","start":"2026-05-05T13:14:59.176712Z","end":"2026-05-05T13:14:59.285855Z","steps":["trace[677090388] 'agreement among raft nodes before linearized reading' (duration: 108.984761ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:14:59.285883Z","caller":"traceutil/trace.go:171","msg":"trace[1668272556] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-service; range_end:; response_count:1; response_revision:13017; }","duration":"109.317651ms","start":"2026-05-05T13:14:59.176551Z","end":"2026-05-05T13:14:59.285868Z","steps":["trace[1668272556] 'agreement among raft nodes before linearized reading' (duration: 109.033553ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:14:59.453264Z","caller":"traceutil/trace.go:171","msg":"trace[1316684812] transaction","detail":"{read_only:false; response_revision:13018; number_of_response:1; }","duration":"158.319318ms","start":"2026-05-05T13:14:59.294909Z","end":"2026-05-05T13:14:59.453228Z","steps":["trace[1316684812] 'process raft request' (duration: 90.927362ms)","trace[1316684812] 'compare' (duration: 66.88004ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:14:59.628457Z","caller":"traceutil/trace.go:171","msg":"trace[1836546397] transaction","detail":"{read_only:false; response_revision:13020; number_of_response:1; }","duration":"171.21481ms","start":"2026-05-05T13:14:59.457211Z","end":"2026-05-05T13:14:59.628426Z","steps":["trace[1836546397] 'process raft request' (duration: 109.182797ms)","trace[1836546397] 'compare' (duration: 61.648743ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:14:59.646141Z","caller":"traceutil/trace.go:171","msg":"trace[1484660835] transaction","detail":"{read_only:false; response_revision:13021; number_of_response:1; }","duration":"188.340724ms","start":"2026-05-05T13:14:59.457782Z","end":"2026-05-05T13:14:59.646122Z","steps":["trace[1484660835] 'process raft request' (duration: 188.101317ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:14:59.646423Z","caller":"traceutil/trace.go:171","msg":"trace[1224661207] transaction","detail":"{read_only:false; response_revision:13022; number_of_response:1; }","duration":"185.435033ms","start":"2026-05-05T13:14:59.460975Z","end":"2026-05-05T13:14:59.64641Z","steps":["trace[1224661207] 'process raft request' (duration: 185.085412ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:14:59.793063Z","caller":"traceutil/trace.go:171","msg":"trace[552056372] linearizableReadLoop","detail":"{readStateIndex:13456; appliedIndex:13454; }","duration":"124.937543ms","start":"2026-05-05T13:14:59.668101Z","end":"2026-05-05T13:14:59.793038Z","steps":["trace[552056372] 'read index received' (duration: 67.275622ms)","trace[552056372] 'applied index is now lower than readState.Index' (duration: 57.660731ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:14:59.793263Z","caller":"traceutil/trace.go:171","msg":"trace[749583156] transaction","detail":"{read_only:false; response_revision:13024; number_of_response:1; }","duration":"142.088987ms","start":"2026-05-05T13:14:59.651149Z","end":"2026-05-05T13:14:59.793238Z","steps":["trace[749583156] 'process raft request' (duration: 84.290223ms)","trace[749583156] 'compare' (duration: 57.369402ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:14:59.793332Z","caller":"traceutil/trace.go:171","msg":"trace[1490634511] transaction","detail":"{read_only:false; response_revision:13025; number_of_response:1; }","duration":"140.922203ms","start":"2026-05-05T13:14:59.652395Z","end":"2026-05-05T13:14:59.793317Z","steps":["trace[1490634511] 'process raft request' (duration: 140.569603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:14:59.79328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.986318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/placement-ks-user-rrzn6\" ","response":"range_response_count:1 size:7669"} {"level":"info","ts":"2026-05-05T13:14:59.793419Z","caller":"traceutil/trace.go:171","msg":"trace[676721873] range","detail":"{range_begin:/registry/pods/openstack/placement-ks-user-rrzn6; range_end:; response_count:1; response_revision:13025; }","duration":"134.172764ms","start":"2026-05-05T13:14:59.659228Z","end":"2026-05-05T13:14:59.793401Z","steps":["trace[676721873] 'agreement among raft nodes before linearized reading' (duration: 133.906486ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:00.451727Z","caller":"traceutil/trace.go:171","msg":"trace[2010637210] linearizableReadLoop","detail":"{readStateIndex:13460; appliedIndex:13459; }","duration":"101.483319ms","start":"2026-05-05T13:15:00.350221Z","end":"2026-05-05T13:15:00.451704Z","steps":["trace[2010637210] 'read index received' (duration: 101.247172ms)","trace[2010637210] 'applied index is now lower than readState.Index' (duration: 235.267µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:00.451827Z","caller":"traceutil/trace.go:171","msg":"trace[446519728] transaction","detail":"{read_only:false; response_revision:13029; number_of_response:1; }","duration":"181.953934ms","start":"2026-05-05T13:15:00.269836Z","end":"2026-05-05T13:15:00.45179Z","steps":["trace[446519728] 'process raft request' (duration: 181.724018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:00.451979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.744286ms","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-05-05T13:15:00.452029Z","caller":"traceutil/trace.go:171","msg":"trace[1435868231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13029; }","duration":"101.842729ms","start":"2026-05-05T13:15:00.350172Z","end":"2026-05-05T13:15:00.452015Z","steps":["trace[1435868231] 'agreement among raft nodes before linearized reading' (duration: 101.663294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:00.71601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.776635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:15:00.716159Z","caller":"traceutil/trace.go:171","msg":"trace[490655920] linearizableReadLoop","detail":"{readStateIndex:13463; appliedIndex:13461; }","duration":"190.200836ms","start":"2026-05-05T13:15:00.525945Z","end":"2026-05-05T13:15:00.716146Z","steps":["trace[490655920] 'read index received' (duration: 69.01283ms)","trace[490655920] 'applied index is now lower than readState.Index' (duration: 121.187306ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:00.716233Z","caller":"traceutil/trace.go:171","msg":"trace[1251307524] transaction","detail":"{read_only:false; response_revision:13031; number_of_response:1; }","duration":"259.002862ms","start":"2026-05-05T13:15:00.457218Z","end":"2026-05-05T13:15:00.71622Z","steps":["trace[1251307524] 'process raft request' (duration: 137.806836ms)","trace[1251307524] 'compare' (duration: 120.654821ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:00.716287Z","caller":"traceutil/trace.go:171","msg":"trace[836450883] transaction","detail":"{read_only:false; response_revision:13032; number_of_response:1; }","duration":"258.56477ms","start":"2026-05-05T13:15:00.45771Z","end":"2026-05-05T13:15:00.716274Z","steps":["trace[836450883] 'process raft request' (duration: 258.382635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:00.716364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.428173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:00.716412Z","caller":"traceutil/trace.go:171","msg":"trace[1931313193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13032; }","duration":"190.529516ms","start":"2026-05-05T13:15:00.525871Z","end":"2026-05-05T13:15:00.7164Z","steps":["trace[1931313193] 'agreement among raft nodes before linearized reading' (duration: 190.419883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:00.739214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.39099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:00.739337Z","caller":"traceutil/trace.go:171","msg":"trace[1207675519] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:13033; }","duration":"192.579965ms","start":"2026-05-05T13:15:00.546728Z","end":"2026-05-05T13:15:00.739308Z","steps":["trace[1207675519] 'agreement among raft nodes before linearized reading' (duration: 192.351059ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:01.037113Z","caller":"traceutil/trace.go:171","msg":"trace[2029851561] linearizableReadLoop","detail":"{readStateIndex:13465; appliedIndex:13464; }","duration":"172.634642ms","start":"2026-05-05T13:15:00.864446Z","end":"2026-05-05T13:15:01.03708Z","steps":["trace[2029851561] 'read index received' (duration: 172.506488ms)","trace[2029851561] 'applied index is now lower than readState.Index' (duration: 126.814µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:01.037168Z","caller":"traceutil/trace.go:171","msg":"trace[992518902] transaction","detail":"{read_only:false; response_revision:13034; number_of_response:1; }","duration":"186.887804ms","start":"2026-05-05T13:15:00.850241Z","end":"2026-05-05T13:15:01.037129Z","steps":["trace[992518902] 'process raft request' (duration: 186.676848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:01.037316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.861837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T13:15:01.037351Z","caller":"traceutil/trace.go:171","msg":"trace[549058501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13034; }","duration":"172.960441ms","start":"2026-05-05T13:15:00.864381Z","end":"2026-05-05T13:15:01.037342Z","steps":["trace[549058501] 'agreement among raft nodes before linearized reading' (duration: 172.793506ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:01.160248Z","caller":"traceutil/trace.go:171","msg":"trace[1650366458] transaction","detail":"{read_only:false; response_revision:13036; number_of_response:1; }","duration":"117.481412ms","start":"2026-05-05T13:15:01.042732Z","end":"2026-05-05T13:15:01.160214Z","steps":["trace[1650366458] 'process raft request' (duration: 33.173588ms)","trace[1650366458] 'compare' (duration: 84.15496ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:01.182391Z","caller":"traceutil/trace.go:171","msg":"trace[1433711596] transaction","detail":"{read_only:false; response_revision:13037; number_of_response:1; }","duration":"135.064368ms","start":"2026-05-05T13:15:01.047305Z","end":"2026-05-05T13:15:01.18237Z","steps":["trace[1433711596] 'process raft request' (duration: 134.968926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:02.102765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.019975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T13:15:02.102866Z","caller":"traceutil/trace.go:171","msg":"trace[1212564387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13039; }","duration":"304.18928ms","start":"2026-05-05T13:15:01.798651Z","end":"2026-05-05T13:15:02.102841Z","steps":["trace[1212564387] 'range keys from in-memory index tree' (duration: 303.846181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:02.10292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:01.798633Z","time spent":"304.274863ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T13:15:02.145184Z","caller":"traceutil/trace.go:171","msg":"trace[1128808388] transaction","detail":"{read_only:false; response_revision:13040; number_of_response:1; }","duration":"180.175525ms","start":"2026-05-05T13:15:01.964981Z","end":"2026-05-05T13:15:02.145156Z","steps":["trace[1128808388] 'process raft request' (duration: 180.010929ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:03.193366Z","caller":"traceutil/trace.go:171","msg":"trace[710787257] linearizableReadLoop","detail":"{readStateIndex:13476; appliedIndex:13475; }","duration":"466.157201ms","start":"2026-05-05T13:15:02.727182Z","end":"2026-05-05T13:15:03.193339Z","steps":["trace[710787257] 'read index received' (duration: 466.051868ms)","trace[710787257] 'applied index is now lower than readState.Index' (duration: 104.193µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:03.19352Z","caller":"traceutil/trace.go:171","msg":"trace[314181522] transaction","detail":"{read_only:false; response_revision:13045; number_of_response:1; }","duration":"469.887666ms","start":"2026-05-05T13:15:02.723589Z","end":"2026-05-05T13:15:03.193477Z","steps":["trace[314181522] 'process raft request' (duration: 469.561297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:03.193673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.469859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-05-05T13:15:03.19374Z","caller":"traceutil/trace.go:171","msg":"trace[478164203] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:13045; }","duration":"466.596103ms","start":"2026-05-05T13:15:02.727123Z","end":"2026-05-05T13:15:03.193719Z","steps":["trace[478164203] 'agreement among raft nodes before linearized reading' (duration: 466.353986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:03.193754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:02.723563Z","time spent":"470.089111ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:15:03.193799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:02.727102Z","time spent":"466.682185ms","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5707,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-05T13:15:03.461315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.43351ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T13:15:03.461617Z","caller":"traceutil/trace.go:171","msg":"trace[1766297877] linearizableReadLoop","detail":"{readStateIndex:13479; appliedIndex:13476; }","duration":"268.158481ms","start":"2026-05-05T13:15:03.193439Z","end":"2026-05-05T13:15:03.461598Z","steps":["trace[1766297877] 'read index received' (duration: 57.34729ms)","trace[1766297877] 'applied index is now lower than readState.Index' (duration: 210.809891ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:03.46167Z","caller":"traceutil/trace.go:171","msg":"trace[486250997] transaction","detail":"{read_only:false; response_revision:13047; number_of_response:1; }","duration":"278.613957ms","start":"2026-05-05T13:15:03.183036Z","end":"2026-05-05T13:15:03.46165Z","steps":["trace[486250997] 'process raft request' (duration: 278.493734ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:03.461672Z","caller":"traceutil/trace.go:171","msg":"trace[1360118021] transaction","detail":"{read_only:false; response_revision:13046; number_of_response:1; }","duration":"413.882211ms","start":"2026-05-05T13:15:03.047768Z","end":"2026-05-05T13:15:03.46165Z","steps":["trace[1360118021] 'process raft request' (duration: 413.641614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:03.461826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:03.047742Z","time spent":"413.992734ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:15:03.461833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.369309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T13:15:03.461944Z","caller":"traceutil/trace.go:171","msg":"trace[1015034618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13047; }","duration":"297.532393ms","start":"2026-05-05T13:15:03.164401Z","end":"2026-05-05T13:15:03.461933Z","steps":["trace[1015034618] 'agreement among raft nodes before linearized reading' (duration: 297.282665ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:03.693941Z","caller":"traceutil/trace.go:171","msg":"trace[1995070384] linearizableReadLoop","detail":"{readStateIndex:13482; appliedIndex:13481; }","duration":"165.288053ms","start":"2026-05-05T13:15:03.528629Z","end":"2026-05-05T13:15:03.693917Z","steps":["trace[1995070384] 'read index received' (duration: 165.119038ms)","trace[1995070384] 'applied index is now lower than readState.Index' (duration: 168.055µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:03.693993Z","caller":"traceutil/trace.go:171","msg":"trace[2002936623] transaction","detail":"{read_only:false; response_revision:13050; number_of_response:1; }","duration":"167.456194ms","start":"2026-05-05T13:15:03.526521Z","end":"2026-05-05T13:15:03.693977Z","steps":["trace[2002936623] 'process raft request' (duration: 167.218817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:03.694222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.555421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-05-05T13:15:03.69493Z","caller":"traceutil/trace.go:171","msg":"trace[1242480809] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:13050; }","duration":"166.293343ms","start":"2026-05-05T13:15:03.52861Z","end":"2026-05-05T13:15:03.694903Z","steps":["trace[1242480809] 'agreement among raft nodes before linearized reading' (duration: 165.411967ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:04.598118Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11036} {"level":"warn","ts":"2026-05-05T13:15:04.598985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.325533ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:5"} {"level":"info","ts":"2026-05-05T13:15:04.599082Z","caller":"traceutil/trace.go:171","msg":"trace[2136412720] linearizableReadLoop","detail":"{readStateIndex:13488; appliedIndex:13487; }","duration":"135.293505ms","start":"2026-05-05T13:15:04.463771Z","end":"2026-05-05T13:15:04.599064Z","steps":["trace[2136412720] 'read index received' (duration: 20.102899ms)","trace[2136412720] 'applied index is now lower than readState.Index' (duration: 115.189126ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:04.599128Z","caller":"traceutil/trace.go:171","msg":"trace[441473339] compact","detail":"{revision:11036; response_revision:13055; }","duration":"137.991501ms","start":"2026-05-05T13:15:04.461111Z","end":"2026-05-05T13:15:04.599102Z","steps":["trace[441473339] 'check and update compact revision' (duration: 114.2191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:04.599322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.509702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/placement-ks-user-rrzn6\" ","response":"range_response_count:1 size:8972"} {"level":"info","ts":"2026-05-05T13:15:04.599607Z","caller":"traceutil/trace.go:171","msg":"trace[380403758] range","detail":"{range_begin:/registry/pods/openstack/placement-ks-user-rrzn6; range_end:; response_count:1; response_revision:13055; }","duration":"135.675677ms","start":"2026-05-05T13:15:04.463726Z","end":"2026-05-05T13:15:04.599401Z","steps":["trace[380403758] 'agreement among raft nodes before linearized reading' (duration: 135.413668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:04.685499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.556369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T13:15:04.685598Z","caller":"traceutil/trace.go:171","msg":"trace[1919204289] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13056; }","duration":"184.698863ms","start":"2026-05-05T13:15:04.500876Z","end":"2026-05-05T13:15:04.685575Z","steps":["trace[1919204289] 'agreement among raft nodes before linearized reading' (duration: 184.498137ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:04.685929Z","caller":"traceutil/trace.go:171","msg":"trace[1987055135] transaction","detail":"{read_only:false; response_revision:13056; number_of_response:1; }","duration":"178.173147ms","start":"2026-05-05T13:15:04.50773Z","end":"2026-05-05T13:15:04.685904Z","steps":["trace[1987055135] 'process raft request' (duration: 113.87291ms)","trace[1987055135] 'compare' (duration: 63.658769ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:04.686058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.725923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:04.686126Z","caller":"traceutil/trace.go:171","msg":"trace[772766841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13056; }","duration":"161.834866ms","start":"2026-05-05T13:15:04.524272Z","end":"2026-05-05T13:15:04.686107Z","steps":["trace[772766841] 'agreement among raft nodes before linearized reading' (duration: 161.733254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:04.68642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.664997ms","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-05-05T13:15:04.686475Z","caller":"traceutil/trace.go:171","msg":"trace[307353405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13056; }","duration":"123.75983ms","start":"2026-05-05T13:15:04.562702Z","end":"2026-05-05T13:15:04.686462Z","steps":["trace[307353405] 'agreement among raft nodes before linearized reading' (duration: 123.622987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:04.885877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.773832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:15:04.886129Z","caller":"traceutil/trace.go:171","msg":"trace[23639323] transaction","detail":"{read_only:false; response_revision:13059; number_of_response:1; }","duration":"195.358124ms","start":"2026-05-05T13:15:04.690752Z","end":"2026-05-05T13:15:04.88611Z","steps":["trace[23639323] 'process raft request' (duration: 195.314282ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:04.886172Z","caller":"traceutil/trace.go:171","msg":"trace[1169261267] transaction","detail":"{read_only:false; response_revision:13057; number_of_response:1; }","duration":"277.953278ms","start":"2026-05-05T13:15:04.608173Z","end":"2026-05-05T13:15:04.886126Z","steps":["trace[1169261267] 'process raft request' (duration: 90.837067ms)","trace[1169261267] 'compare' (duration: 50.746615ms)","trace[1169261267] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/openstack/placement-ks-user-rrzn6; req_size:9192; } (duration: 135.746079ms)"],"step_count":3} {"level":"warn","ts":"2026-05-05T13:15:04.886263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.656497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:04.88629Z","caller":"traceutil/trace.go:171","msg":"trace[1011970903] transaction","detail":"{read_only:false; response_revision:13058; number_of_response:1; }","duration":"196.607888ms","start":"2026-05-05T13:15:04.689672Z","end":"2026-05-05T13:15:04.88628Z","steps":["trace[1011970903] 'process raft request' (duration: 196.30575ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:04.886122Z","caller":"traceutil/trace.go:171","msg":"trace[901404498] linearizableReadLoop","detail":"{readStateIndex:13490; appliedIndex:13489; }","duration":"198.514373ms","start":"2026-05-05T13:15:04.687577Z","end":"2026-05-05T13:15:04.886091Z","steps":["trace[901404498] 'read index received' (duration: 11.462814ms)","trace[901404498] 'applied index is now lower than readState.Index' (duration: 187.047639ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:04.886323Z","caller":"traceutil/trace.go:171","msg":"trace[605157434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13059; }","duration":"198.760939ms","start":"2026-05-05T13:15:04.687546Z","end":"2026-05-05T13:15:04.886307Z","steps":["trace[605157434] 'agreement among raft nodes before linearized reading' (duration: 198.618325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:04.886867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.807864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-05-05T13:15:04.886956Z","caller":"traceutil/trace.go:171","msg":"trace[1898042584] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:13059; }","duration":"138.976909ms","start":"2026-05-05T13:15:04.747957Z","end":"2026-05-05T13:15:04.886934Z","steps":["trace[1898042584] 'agreement among raft nodes before linearized reading' (duration: 138.496295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:05.323283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.393837ms","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-05-05T13:15:05.323371Z","caller":"traceutil/trace.go:171","msg":"trace[502371741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13059; }","duration":"124.553861ms","start":"2026-05-05T13:15:05.198798Z","end":"2026-05-05T13:15:05.323352Z","steps":["trace[502371741] 'range keys from in-memory index tree' (duration: 124.207102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:05.527045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.568289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:15:05.527205Z","caller":"traceutil/trace.go:171","msg":"trace[561743926] transaction","detail":"{read_only:false; response_revision:13060; number_of_response:1; }","duration":"199.419537ms","start":"2026-05-05T13:15:05.327736Z","end":"2026-05-05T13:15:05.527156Z","steps":["trace[561743926] 'process raft request' (duration: 61.637362ms)","trace[561743926] 'compare' (duration: 137.405135ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:05.723605Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11036,"took":"1.121065246s","hash":3731414753,"current-db-size-bytes":57389056,"current-db-size":"57 MB","current-db-size-in-use-bytes":27918336,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T13:15:05.723681Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3731414753,"revision":11036,"compact-revision":8333} {"level":"warn","ts":"2026-05-05T13:15:05.724098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.012349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:15:05.724191Z","caller":"traceutil/trace.go:171","msg":"trace[36352733] linearizableReadLoop","detail":"{readStateIndex:13494; appliedIndex:13493; }","duration":"197.01861ms","start":"2026-05-05T13:15:05.527136Z","end":"2026-05-05T13:15:05.724155Z","steps":["trace[36352733] 'read index received' (duration: 39.870027ms)","trace[36352733] 'applied index is now lower than readState.Index' (duration: 157.147403ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:05.72423Z","caller":"traceutil/trace.go:171","msg":"trace[1612133962] transaction","detail":"{read_only:false; response_revision:13061; number_of_response:1; }","duration":"257.8537ms","start":"2026-05-05T13:15:05.466338Z","end":"2026-05-05T13:15:05.724192Z","steps":["trace[1612133962] 'process raft request' (duration: 100.672866ms)","trace[1612133962] 'compare' (duration: 156.904056ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:05.7243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.468806ms","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-05-05T13:15:05.724328Z","caller":"traceutil/trace.go:171","msg":"trace[1587144727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13061; }","duration":"224.518087ms","start":"2026-05-05T13:15:05.4998Z","end":"2026-05-05T13:15:05.724318Z","steps":["trace[1587144727] 'agreement among raft nodes before linearized reading' (duration: 224.444565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:05.724453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.732652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T13:15:05.724441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.693751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-user\" ","response":"range_response_count:1 size:7433"} {"level":"info","ts":"2026-05-05T13:15:05.724483Z","caller":"traceutil/trace.go:171","msg":"trace[2130712524] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-endpoints; range_end:; response_count:0; response_revision:13061; }","duration":"248.794204ms","start":"2026-05-05T13:15:05.475682Z","end":"2026-05-05T13:15:05.724476Z","steps":["trace[2130712524] 'agreement among raft nodes before linearized reading' (duration: 248.749613ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:05.724497Z","caller":"traceutil/trace.go:171","msg":"trace[776008753] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-user; range_end:; response_count:1; response_revision:13061; }","duration":"248.805484ms","start":"2026-05-05T13:15:05.47568Z","end":"2026-05-05T13:15:05.724485Z","steps":["trace[776008753] 'agreement among raft nodes before linearized reading' (duration: 248.620749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:06.450413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.559805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:15:06.450565Z","caller":"traceutil/trace.go:171","msg":"trace[1028992631] transaction","detail":"{read_only:false; response_revision:13063; number_of_response:1; }","duration":"722.142946ms","start":"2026-05-05T13:15:05.728401Z","end":"2026-05-05T13:15:06.450544Z","steps":["trace[1028992631] 'process raft request' (duration: 321.374075ms)","trace[1028992631] 'compare' (duration: 400.3748ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:06.450693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:05.728376Z","time spent":"722.241869ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:15:06.450718Z","caller":"traceutil/trace.go:171","msg":"trace[1716571916] transaction","detail":"{read_only:false; response_revision:13064; number_of_response:1; }","duration":"270.686462ms","start":"2026-05-05T13:15:06.17997Z","end":"2026-05-05T13:15:06.450656Z","steps":["trace[1716571916] 'process raft request' (duration: 270.578619ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:06.450873Z","caller":"traceutil/trace.go:171","msg":"trace[1355251071] linearizableReadLoop","detail":"{readStateIndex:13496; appliedIndex:13495; }","duration":"704.8804ms","start":"2026-05-05T13:15:05.745984Z","end":"2026-05-05T13:15:06.450865Z","steps":["trace[1355251071] 'read index received' (duration: 303.751118ms)","trace[1355251071] 'applied index is now lower than readState.Index' (duration: 401.128292ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:06.450972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.996023ms","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-05-05T13:15:06.450997Z","caller":"traceutil/trace.go:171","msg":"trace[309889221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13064; }","duration":"705.060335ms","start":"2026-05-05T13:15:05.745931Z","end":"2026-05-05T13:15:06.450991Z","steps":["trace[309889221] 'agreement among raft nodes before linearized reading' (duration: 704.993713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:06.451016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:05.745904Z","time spent":"705.107536ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T13:15:06.45113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.071566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sr4kw\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-05T13:15:06.45115Z","caller":"traceutil/trace.go:171","msg":"trace[1570288669] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sr4kw; range_end:; response_count:1; response_revision:13064; }","duration":"693.115277ms","start":"2026-05-05T13:15:05.75803Z","end":"2026-05-05T13:15:06.451145Z","steps":["trace[1570288669] 'agreement among raft nodes before linearized reading' (duration: 693.033865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:06.451165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:05.758018Z","time spent":"693.143748ms","remote":"127.0.0.1:33822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-sr4kw\" "} {"level":"warn","ts":"2026-05-05T13:15:06.451552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.901229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-05T13:15:06.451621Z","caller":"traceutil/trace.go:171","msg":"trace[1079118068] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:13064; }","duration":"108.974611ms","start":"2026-05-05T13:15:06.342627Z","end":"2026-05-05T13:15:06.451602Z","steps":["trace[1079118068] 'agreement among raft nodes before linearized reading' (duration: 108.773515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:06.451616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.614506ms","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-05-05T13:15:06.451691Z","caller":"traceutil/trace.go:171","msg":"trace[910233762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13064; }","duration":"216.714218ms","start":"2026-05-05T13:15:06.234954Z","end":"2026-05-05T13:15:06.451668Z","steps":["trace[910233762] 'agreement among raft nodes before linearized reading' (duration: 216.498592ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:06.654893Z","caller":"traceutil/trace.go:171","msg":"trace[1800497395] transaction","detail":"{read_only:false; response_revision:13066; number_of_response:1; }","duration":"198.877183ms","start":"2026-05-05T13:15:06.455991Z","end":"2026-05-05T13:15:06.654868Z","steps":["trace[1800497395] 'process raft request' (duration: 198.668697ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:06.655092Z","caller":"traceutil/trace.go:171","msg":"trace[1526836856] linearizableReadLoop","detail":"{readStateIndex:13499; appliedIndex:13498; }","duration":"130.675274ms","start":"2026-05-05T13:15:06.524161Z","end":"2026-05-05T13:15:06.654836Z","steps":["trace[1526836856] 'read index received' (duration: 130.425737ms)","trace[1526836856] 'applied index is now lower than readState.Index' (duration: 248.797µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:06.655165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.981062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:06.655254Z","caller":"traceutil/trace.go:171","msg":"trace[259241659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13066; }","duration":"131.086085ms","start":"2026-05-05T13:15:06.524145Z","end":"2026-05-05T13:15:06.655231Z","steps":["trace[259241659] 'agreement among raft nodes before linearized reading' (duration: 130.924331ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:07.0628Z","caller":"traceutil/trace.go:171","msg":"trace[1092546811] transaction","detail":"{read_only:false; response_revision:13068; number_of_response:1; }","duration":"168.465023ms","start":"2026-05-05T13:15:06.89431Z","end":"2026-05-05T13:15:07.062775Z","steps":["trace[1092546811] 'process raft request' (duration: 120.094146ms)","trace[1092546811] 'compare' (duration: 48.216773ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:07.647965Z","caller":"traceutil/trace.go:171","msg":"trace[2064080664] linearizableReadLoop","detail":"{readStateIndex:13503; appliedIndex:13501; }","duration":"130.561262ms","start":"2026-05-05T13:15:07.517384Z","end":"2026-05-05T13:15:07.647945Z","steps":["trace[2064080664] 'read index received' (duration: 16.825686ms)","trace[2064080664] 'applied index is now lower than readState.Index' (duration: 113.734846ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:07.648144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.751017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:07.648173Z","caller":"traceutil/trace.go:171","msg":"trace[481309546] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:13070; }","duration":"130.8697ms","start":"2026-05-05T13:15:07.517296Z","end":"2026-05-05T13:15:07.648165Z","steps":["trace[481309546] 'agreement among raft nodes before linearized reading' (duration: 130.763847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:07.648239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.975637ms","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-05-05T13:15:07.6483Z","caller":"traceutil/trace.go:171","msg":"trace[767220493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13070; }","duration":"117.08025ms","start":"2026-05-05T13:15:07.531207Z","end":"2026-05-05T13:15:07.648287Z","steps":["trace[767220493] 'agreement among raft nodes before linearized reading' (duration: 116.887315ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:07.648756Z","caller":"traceutil/trace.go:171","msg":"trace[428379084] transaction","detail":"{read_only:false; response_revision:13070; number_of_response:1; }","duration":"135.730058ms","start":"2026-05-05T13:15:07.512301Z","end":"2026-05-05T13:15:07.648031Z","steps":["trace[428379084] 'process raft request' (duration: 123.964796ms)","trace[428379084] 'compare' (duration: 11.573707ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:07.835513Z","caller":"traceutil/trace.go:171","msg":"trace[2058499756] linearizableReadLoop","detail":"{readStateIndex:13507; appliedIndex:13504; }","duration":"106.893471ms","start":"2026-05-05T13:15:07.728596Z","end":"2026-05-05T13:15:07.83549Z","steps":["trace[2058499756] 'read index received' (duration: 1.868112ms)","trace[2058499756] 'applied index is now lower than readState.Index' (duration: 105.024279ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:07.835636Z","caller":"traceutil/trace.go:171","msg":"trace[807941367] transaction","detail":"{read_only:false; response_revision:13073; number_of_response:1; }","duration":"107.056466ms","start":"2026-05-05T13:15:07.72857Z","end":"2026-05-05T13:15:07.835626Z","steps":["trace[807941367] 'process raft request' (duration: 106.808069ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:07.835895Z","caller":"traceutil/trace.go:171","msg":"trace[240847028] transaction","detail":"{read_only:false; response_revision:13072; number_of_response:1; }","duration":"167.966509ms","start":"2026-05-05T13:15:07.667892Z","end":"2026-05-05T13:15:07.835858Z","steps":["trace[240847028] 'process raft request' (duration: 88.150562ms)","trace[240847028] 'compare' (duration: 79.189709ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:07.836267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.221953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-user\" ","response":"range_response_count:1 size:7433"} {"level":"info","ts":"2026-05-05T13:15:07.836316Z","caller":"traceutil/trace.go:171","msg":"trace[262250386] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-user; range_end:; response_count:1; response_revision:13074; }","duration":"106.317946ms","start":"2026-05-05T13:15:07.729987Z","end":"2026-05-05T13:15:07.836305Z","steps":["trace[262250386] 'agreement among raft nodes before linearized reading' (duration: 106.065499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:07.836324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.702034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:07.836418Z","caller":"traceutil/trace.go:171","msg":"trace[1246844347] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-endpoints; range_end:; response_count:0; response_revision:13074; }","duration":"107.852289ms","start":"2026-05-05T13:15:07.728548Z","end":"2026-05-05T13:15:07.8364Z","steps":["trace[1246844347] 'agreement among raft nodes before linearized reading' (duration: 107.351045ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:08.502043Z","caller":"traceutil/trace.go:171","msg":"trace[2068667265] transaction","detail":"{read_only:false; response_revision:13076; number_of_response:1; }","duration":"140.41656ms","start":"2026-05-05T13:15:08.361553Z","end":"2026-05-05T13:15:08.50197Z","steps":["trace[2068667265] 'process raft request' (duration: 139.975488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:08.645399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.304729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:15:08.645516Z","caller":"traceutil/trace.go:171","msg":"trace[749041125] transaction","detail":"{read_only:false; response_revision:13077; number_of_response:1; }","duration":"188.599162ms","start":"2026-05-05T13:15:08.456906Z","end":"2026-05-05T13:15:08.645505Z","steps":["trace[749041125] 'process raft request' (duration: 84.125748ms)","trace[749041125] 'compare' (duration: 104.210157ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:08.645689Z","caller":"traceutil/trace.go:171","msg":"trace[1047793241] transaction","detail":"{read_only:false; response_revision:13078; number_of_response:1; }","duration":"139.588837ms","start":"2026-05-05T13:15:08.50609Z","end":"2026-05-05T13:15:08.645679Z","steps":["trace[1047793241] 'process raft request' (duration: 139.387791ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:08.64568Z","caller":"traceutil/trace.go:171","msg":"trace[2020305412] linearizableReadLoop","detail":"{readStateIndex:13512; appliedIndex:13510; }","duration":"131.490168ms","start":"2026-05-05T13:15:08.514162Z","end":"2026-05-05T13:15:08.645652Z","steps":["trace[2020305412] 'read index received' (duration: 26.983684ms)","trace[2020305412] 'applied index is now lower than readState.Index' (duration: 104.503944ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:08.64598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.794346ms","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-05-05T13:15:08.646034Z","caller":"traceutil/trace.go:171","msg":"trace[593881880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13078; }","duration":"131.895449ms","start":"2026-05-05T13:15:08.514124Z","end":"2026-05-05T13:15:08.64602Z","steps":["trace[593881880] 'agreement among raft nodes before linearized reading' (duration: 131.703754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:08.64633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.69725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:08.646414Z","caller":"traceutil/trace.go:171","msg":"trace[1258242191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13078; }","duration":"121.830294ms","start":"2026-05-05T13:15:08.524562Z","end":"2026-05-05T13:15:08.646393Z","steps":["trace[1258242191] 'agreement among raft nodes before linearized reading' (duration: 121.69935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:08.64639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.196497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sr4kw\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-05T13:15:08.646562Z","caller":"traceutil/trace.go:171","msg":"trace[2113385256] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sr4kw; range_end:; response_count:1; response_revision:13078; }","duration":"121.402822ms","start":"2026-05-05T13:15:08.525136Z","end":"2026-05-05T13:15:08.646539Z","steps":["trace[2113385256] 'agreement among raft nodes before linearized reading' (duration: 121.102823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:08.646358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.954033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/placement-ks-user-rrzn6\" ","response":"range_response_count:1 size:9221"} {"level":"info","ts":"2026-05-05T13:15:08.648131Z","caller":"traceutil/trace.go:171","msg":"trace[2049540451] range","detail":"{range_begin:/registry/pods/openstack/placement-ks-user-rrzn6; range_end:; response_count:1; response_revision:13078; }","duration":"132.762484ms","start":"2026-05-05T13:15:08.515353Z","end":"2026-05-05T13:15:08.648115Z","steps":["trace[2049540451] 'agreement among raft nodes before linearized reading' (duration: 130.87817ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:08.924583Z","caller":"traceutil/trace.go:171","msg":"trace[919306677] linearizableReadLoop","detail":"{readStateIndex:13514; appliedIndex:13513; }","duration":"214.896386ms","start":"2026-05-05T13:15:08.709665Z","end":"2026-05-05T13:15:08.924562Z","steps":["trace[919306677] 'read index received' (duration: 214.72024ms)","trace[919306677] 'applied index is now lower than readState.Index' (duration: 174.776µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:08.924853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.30388ms","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-05-05T13:15:08.924914Z","caller":"traceutil/trace.go:171","msg":"trace[610652998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13080; }","duration":"266.389742ms","start":"2026-05-05T13:15:08.658505Z","end":"2026-05-05T13:15:08.924895Z","steps":["trace[610652998] 'agreement among raft nodes before linearized reading' (duration: 266.233477ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:08.925024Z","caller":"traceutil/trace.go:171","msg":"trace[1239930196] transaction","detail":"{read_only:false; response_revision:13080; number_of_response:1; }","duration":"266.455224ms","start":"2026-05-05T13:15:08.658519Z","end":"2026-05-05T13:15:08.924974Z","steps":["trace[1239930196] 'process raft request' (duration: 265.878987ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:09.213526Z","caller":"traceutil/trace.go:171","msg":"trace[1827875342] linearizableReadLoop","detail":"{readStateIndex:13515; appliedIndex:13514; }","duration":"273.300287ms","start":"2026-05-05T13:15:08.940197Z","end":"2026-05-05T13:15:09.213498Z","steps":["trace[1827875342] 'read index received' (duration: 273.141333ms)","trace[1827875342] 'applied index is now lower than readState.Index' (duration: 157.814µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:09.213657Z","caller":"traceutil/trace.go:171","msg":"trace[471953248] transaction","detail":"{read_only:false; response_revision:13081; number_of_response:1; }","duration":"282.155948ms","start":"2026-05-05T13:15:08.931468Z","end":"2026-05-05T13:15:09.213624Z","steps":["trace[471953248] 'process raft request' (duration: 281.8738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:09.213801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.719469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T13:15:09.213856Z","caller":"traceutil/trace.go:171","msg":"trace[133682610] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13081; }","duration":"146.844793ms","start":"2026-05-05T13:15:09.066995Z","end":"2026-05-05T13:15:09.21384Z","steps":["trace[133682610] 'agreement among raft nodes before linearized reading' (duration: 146.665538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:09.21442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.851993ms","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-05-05T13:15:09.214569Z","caller":"traceutil/trace.go:171","msg":"trace[1778261188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13081; }","duration":"274.394878ms","start":"2026-05-05T13:15:08.940148Z","end":"2026-05-05T13:15:09.214543Z","steps":["trace[1778261188] 'agreement among raft nodes before linearized reading' (duration: 273.486833ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:09.381067Z","caller":"traceutil/trace.go:171","msg":"trace[2103119140] transaction","detail":"{read_only:false; response_revision:13083; number_of_response:1; }","duration":"162.222896ms","start":"2026-05-05T13:15:09.218815Z","end":"2026-05-05T13:15:09.381038Z","steps":["trace[2103119140] 'process raft request' (duration: 81.01005ms)","trace[2103119140] 'compare' (duration: 81.098243ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:10.779619Z","caller":"traceutil/trace.go:171","msg":"trace[1306582271] transaction","detail":"{read_only:false; response_revision:13088; number_of_response:1; }","duration":"128.05166ms","start":"2026-05-05T13:15:10.65154Z","end":"2026-05-05T13:15:10.779592Z","steps":["trace[1306582271] 'process raft request' (duration: 127.798433ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:10.818448Z","caller":"traceutil/trace.go:171","msg":"trace[1122384825] linearizableReadLoop","detail":"{readStateIndex:13523; appliedIndex:13521; }","duration":"104.853246ms","start":"2026-05-05T13:15:10.713573Z","end":"2026-05-05T13:15:10.818426Z","steps":["trace[1122384825] 'read index received' (duration: 65.811832ms)","trace[1122384825] 'applied index is now lower than readState.Index' (duration: 39.040124ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:10.818538Z","caller":"traceutil/trace.go:171","msg":"trace[1681792025] transaction","detail":"{read_only:false; response_revision:13089; number_of_response:1; }","duration":"165.108819ms","start":"2026-05-05T13:15:10.653389Z","end":"2026-05-05T13:15:10.818498Z","steps":["trace[1681792025] 'process raft request' (duration: 164.920263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:10.818719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.101772ms","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-05-05T13:15:10.818779Z","caller":"traceutil/trace.go:171","msg":"trace[1517711959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13089; }","duration":"105.242426ms","start":"2026-05-05T13:15:10.713521Z","end":"2026-05-05T13:15:10.818764Z","steps":["trace[1517711959] 'agreement among raft nodes before linearized reading' (duration: 105.02363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:12.010931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.83388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:15:12.011044Z","caller":"traceutil/trace.go:171","msg":"trace[1772855955] linearizableReadLoop","detail":"{readStateIndex:13527; appliedIndex:13526; }","duration":"688.6341ms","start":"2026-05-05T13:15:11.322394Z","end":"2026-05-05T13:15:12.011028Z","steps":["trace[1772855955] 'read index received' (duration: 459.529604ms)","trace[1772855955] 'applied index is now lower than readState.Index' (duration: 229.103166ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:12.011145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.745043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:15:12.011137Z","caller":"traceutil/trace.go:171","msg":"trace[2026220198] transaction","detail":"{read_only:false; response_revision:13093; number_of_response:1; }","duration":"732.265463ms","start":"2026-05-05T13:15:11.278828Z","end":"2026-05-05T13:15:12.011094Z","steps":["trace[2026220198] 'process raft request' (duration: 503.159736ms)","trace[2026220198] 'compare' (duration: 228.592233ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:12.011184Z","caller":"traceutil/trace.go:171","msg":"trace[2019102201] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:13093; }","duration":"688.811085ms","start":"2026-05-05T13:15:11.32236Z","end":"2026-05-05T13:15:12.011172Z","steps":["trace[2019102201] 'agreement among raft nodes before linearized reading' (duration: 688.739003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:12.011221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:11.322342Z","time spent":"688.868607ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-05-05T13:15:12.01144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:11.278806Z","time spent":"732.395036ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:15:12.243884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.359174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:15:12.244226Z","caller":"traceutil/trace.go:171","msg":"trace[1352536229] transaction","detail":"{read_only:false; response_revision:13094; number_of_response:1; }","duration":"329.244718ms","start":"2026-05-05T13:15:11.914774Z","end":"2026-05-05T13:15:12.244019Z","steps":["trace[1352536229] 'process raft request' (duration: 209.624037ms)","trace[1352536229] 'compare' (duration: 119.171909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:12.244302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:11.914746Z","time spent":"329.524948ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:15:12.299842Z","caller":"traceutil/trace.go:171","msg":"trace[522641090] linearizableReadLoop","detail":"{readStateIndex:13529; appliedIndex:13527; }","duration":"288.744783ms","start":"2026-05-05T13:15:12.01107Z","end":"2026-05-05T13:15:12.299815Z","steps":["trace[522641090] 'read index received' (duration: 113.353285ms)","trace[522641090] 'applied index is now lower than readState.Index' (duration: 175.390218ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:12.299954Z","caller":"traceutil/trace.go:171","msg":"trace[662740185] transaction","detail":"{read_only:false; response_revision:13095; number_of_response:1; }","duration":"357.304432ms","start":"2026-05-05T13:15:11.942639Z","end":"2026-05-05T13:15:12.299943Z","steps":["trace[662740185] 'process raft request' (duration: 357.006913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:12.300072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:11.942617Z","time spent":"357.363563ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:15:12.300414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.975597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:12.300457Z","caller":"traceutil/trace.go:171","msg":"trace[579746626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13095; }","duration":"558.058709ms","start":"2026-05-05T13:15:11.742388Z","end":"2026-05-05T13:15:12.300447Z","steps":["trace[579746626] 'agreement among raft nodes before linearized reading' (duration: 557.978317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:12.30051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:11.742368Z","time spent":"558.134701ms","remote":"127.0.0.1:33668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T13:15:12.300612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.247811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-user\" ","response":"range_response_count:1 size:7433"} {"level":"info","ts":"2026-05-05T13:15:12.300686Z","caller":"traceutil/trace.go:171","msg":"trace[713183244] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-user; range_end:; response_count:1; response_revision:13095; }","duration":"357.306013ms","start":"2026-05-05T13:15:11.943356Z","end":"2026-05-05T13:15:12.300662Z","steps":["trace[713183244] 'agreement among raft nodes before linearized reading' (duration: 357.178879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:12.300469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.136946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T13:15:12.300717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.308998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T13:15:12.30074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:11.943317Z","time spent":"357.413706ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":7457,"request content":"key:\"/registry/jobs/openstack/placement-ks-user\" "} {"level":"info","ts":"2026-05-05T13:15:12.300775Z","caller":"traceutil/trace.go:171","msg":"trace[1317636589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13095; }","duration":"538.478985ms","start":"2026-05-05T13:15:11.762266Z","end":"2026-05-05T13:15:12.300745Z","steps":["trace[1317636589] 'agreement among raft nodes before linearized reading' (duration: 537.969571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:12.30092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:11.762256Z","time spent":"538.6215ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T13:15:12.301006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.506652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-05T13:15:12.301017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.266144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:12.301046Z","caller":"traceutil/trace.go:171","msg":"trace[1654454239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13095; }","duration":"916.585104ms","start":"2026-05-05T13:15:11.384452Z","end":"2026-05-05T13:15:12.301037Z","steps":["trace[1654454239] 'agreement among raft nodes before linearized reading' (duration: 916.44309ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:12.301129Z","caller":"traceutil/trace.go:171","msg":"trace[2048272459] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-endpoints; range_end:; response_count:0; response_revision:13095; }","duration":"384.351867ms","start":"2026-05-05T13:15:11.916696Z","end":"2026-05-05T13:15:12.301047Z","steps":["trace[2048272459] 'agreement among raft nodes before linearized reading' (duration: 384.286695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:12.301149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:11.384427Z","time spent":"916.714218ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T13:15:12.301164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:11.916672Z","time spent":"384.484421ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" "} {"level":"info","ts":"2026-05-05T13:15:12.300753Z","caller":"traceutil/trace.go:171","msg":"trace[966810504] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:13095; }","duration":"667.38449ms","start":"2026-05-05T13:15:11.633358Z","end":"2026-05-05T13:15:12.300742Z","steps":["trace[966810504] 'agreement among raft nodes before linearized reading' (duration: 667.319548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:12.301246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:11.633335Z","time spent":"667.903015ms","remote":"127.0.0.1:59154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-05T13:15:12.325589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.397021ms","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-05-05T13:15:12.325689Z","caller":"traceutil/trace.go:171","msg":"trace[111943443] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:13096; }","duration":"255.554406ms","start":"2026-05-05T13:15:12.07011Z","end":"2026-05-05T13:15:12.325664Z","steps":["trace[111943443] 'agreement among raft nodes before linearized reading' (duration: 255.33996ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:12.32559Z","caller":"traceutil/trace.go:171","msg":"trace[331220574] transaction","detail":"{read_only:false; response_revision:13096; number_of_response:1; }","duration":"130.950972ms","start":"2026-05-05T13:15:12.194599Z","end":"2026-05-05T13:15:12.32555Z","steps":["trace[331220574] 'process raft request' (duration: 130.644493ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:12.572985Z","caller":"traceutil/trace.go:171","msg":"trace[1406707926] transaction","detail":"{read_only:false; response_revision:13097; number_of_response:1; }","duration":"266.224757ms","start":"2026-05-05T13:15:12.306726Z","end":"2026-05-05T13:15:12.572951Z","steps":["trace[1406707926] 'process raft request' (duration: 169.508812ms)","trace[1406707926] 'compare' (duration: 96.422467ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:12.573024Z","caller":"traceutil/trace.go:171","msg":"trace[175446776] linearizableReadLoop","detail":"{readStateIndex:13532; appliedIndex:13530; }","duration":"227.266515ms","start":"2026-05-05T13:15:12.34573Z","end":"2026-05-05T13:15:12.572996Z","steps":["trace[175446776] 'read index received' (duration: 130.458678ms)","trace[175446776] 'applied index is now lower than readState.Index' (duration: 96.806197ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:12.573181Z","caller":"traceutil/trace.go:171","msg":"trace[190357026] transaction","detail":"{read_only:false; response_revision:13098; number_of_response:1; }","duration":"266.082083ms","start":"2026-05-05T13:15:12.307082Z","end":"2026-05-05T13:15:12.573165Z","steps":["trace[190357026] 'process raft request' (duration: 265.793785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:12.573307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.37313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T13:15:12.573418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.688158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-05-05T13:15:12.573451Z","caller":"traceutil/trace.go:171","msg":"trace[320733691] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:13098; }","duration":"122.574216ms","start":"2026-05-05T13:15:12.45085Z","end":"2026-05-05T13:15:12.573424Z","steps":["trace[320733691] 'agreement among raft nodes before linearized reading' (duration: 122.378191ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:12.573483Z","caller":"traceutil/trace.go:171","msg":"trace[494125286] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:13098; }","duration":"227.75631ms","start":"2026-05-05T13:15:12.345707Z","end":"2026-05-05T13:15:12.573464Z","steps":["trace[494125286] 'agreement among raft nodes before linearized reading' (duration: 227.519443ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:13.008176Z","caller":"traceutil/trace.go:171","msg":"trace[138699820] linearizableReadLoop","detail":"{readStateIndex:13534; appliedIndex:13533; }","duration":"184.730003ms","start":"2026-05-05T13:15:12.823417Z","end":"2026-05-05T13:15:13.008147Z","steps":["trace[138699820] 'read index received' (duration: 94.139162ms)","trace[138699820] 'applied index is now lower than readState.Index' (duration: 90.589251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:13.008467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.032971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T13:15:13.008508Z","caller":"traceutil/trace.go:171","msg":"trace[869243535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13099; }","duration":"185.128474ms","start":"2026-05-05T13:15:12.82337Z","end":"2026-05-05T13:15:13.008498Z","steps":["trace[869243535] 'agreement among raft nodes before linearized reading' (duration: 184.942469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:13.008548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.414698ms","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-05-05T13:15:13.008655Z","caller":"traceutil/trace.go:171","msg":"trace[1483170210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13099; }","duration":"164.565432ms","start":"2026-05-05T13:15:12.844068Z","end":"2026-05-05T13:15:13.008633Z","steps":["trace[1483170210] 'agreement among raft nodes before linearized reading' (duration: 164.308355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:13.008548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.543285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sr4kw\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-05T13:15:13.008838Z","caller":"traceutil/trace.go:171","msg":"trace[425778908] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sr4kw; range_end:; response_count:1; response_revision:13099; }","duration":"157.874573ms","start":"2026-05-05T13:15:12.85095Z","end":"2026-05-05T13:15:13.008824Z","steps":["trace[425778908] 'agreement among raft nodes before linearized reading' (duration: 157.458211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:13.897172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.270715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:13.897228Z","caller":"traceutil/trace.go:171","msg":"trace[1696839835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13102; }","duration":"373.369908ms","start":"2026-05-05T13:15:13.523846Z","end":"2026-05-05T13:15:13.897216Z","steps":["trace[1696839835] 'range keys from in-memory index tree' (duration: 373.128221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:13.897258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:13.523828Z","time spent":"373.417778ms","remote":"127.0.0.1:33668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T13:15:13.897929Z","caller":"traceutil/trace.go:171","msg":"trace[1629746704] transaction","detail":"{read_only:false; response_revision:13103; number_of_response:1; }","duration":"204.362918ms","start":"2026-05-05T13:15:13.693557Z","end":"2026-05-05T13:15:13.89792Z","steps":["trace[1629746704] 'process raft request' (duration: 157.849333ms)","trace[1629746704] 'compare' (duration: 45.496916ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:13.897915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.409684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-05T13:15:13.897996Z","caller":"traceutil/trace.go:171","msg":"trace[817270562] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13102; }","duration":"537.539478ms","start":"2026-05-05T13:15:13.360436Z","end":"2026-05-05T13:15:13.897976Z","steps":["trace[817270562] 'range keys from in-memory index tree' (duration: 536.712305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:13.898047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:13.360416Z","time spent":"537.622421ms","remote":"127.0.0.1:33822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-05T13:15:15.184662Z","caller":"traceutil/trace.go:171","msg":"trace[75602774] linearizableReadLoop","detail":"{readStateIndex:13545; appliedIndex:13544; }","duration":"125.91752ms","start":"2026-05-05T13:15:15.058722Z","end":"2026-05-05T13:15:15.18464Z","steps":["trace[75602774] 'read index received' (duration: 125.715665ms)","trace[75602774] 'applied index is now lower than readState.Index' (duration: 200.645µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:15.184767Z","caller":"traceutil/trace.go:171","msg":"trace[2037963256] transaction","detail":"{read_only:false; response_revision:13110; number_of_response:1; }","duration":"128.963096ms","start":"2026-05-05T13:15:15.055766Z","end":"2026-05-05T13:15:15.184729Z","steps":["trace[2037963256] 'process raft request' (duration: 128.729269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:15.184915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.641054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T13:15:15.184976Z","caller":"traceutil/trace.go:171","msg":"trace[1354682561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13110; }","duration":"118.752216ms","start":"2026-05-05T13:15:15.066212Z","end":"2026-05-05T13:15:15.184965Z","steps":["trace[1354682561] 'agreement among raft nodes before linearized reading' (duration: 118.574181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:15.185411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.667251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:61 size:566728"} {"level":"info","ts":"2026-05-05T13:15:15.185492Z","caller":"traceutil/trace.go:171","msg":"trace[635670920] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:61; response_revision:13110; }","duration":"126.778015ms","start":"2026-05-05T13:15:15.058696Z","end":"2026-05-05T13:15:15.185474Z","steps":["trace[635670920] 'agreement among raft nodes before linearized reading' (duration: 126.058335ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:15.353177Z","caller":"traceutil/trace.go:171","msg":"trace[1908375265] transaction","detail":"{read_only:false; response_revision:13111; number_of_response:1; }","duration":"163.987536ms","start":"2026-05-05T13:15:15.189163Z","end":"2026-05-05T13:15:15.353151Z","steps":["trace[1908375265] 'process raft request' (duration: 67.94156ms)","trace[1908375265] 'compare' (duration: 95.817719ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:15.353424Z","caller":"traceutil/trace.go:171","msg":"trace[749995501] transaction","detail":"{read_only:false; response_revision:13112; number_of_response:1; }","duration":"163.895264ms","start":"2026-05-05T13:15:15.18951Z","end":"2026-05-05T13:15:15.353405Z","steps":["trace[749995501] 'process raft request' (duration: 163.573055ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:16.866665Z","caller":"traceutil/trace.go:171","msg":"trace[1570204727] transaction","detail":"{read_only:false; response_revision:13119; number_of_response:1; }","duration":"210.628685ms","start":"2026-05-05T13:15:16.656012Z","end":"2026-05-05T13:15:16.866641Z","steps":["trace[1570204727] 'process raft request' (duration: 210.293795ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:16.957181Z","caller":"traceutil/trace.go:171","msg":"trace[970447063] transaction","detail":"{read_only:false; response_revision:13120; number_of_response:1; }","duration":"271.422154ms","start":"2026-05-05T13:15:16.685739Z","end":"2026-05-05T13:15:16.957161Z","steps":["trace[970447063] 'process raft request' (duration: 271.305661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:17.129687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.557682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:15:17.130058Z","caller":"traceutil/trace.go:171","msg":"trace[43661948] transaction","detail":"{read_only:false; response_revision:13121; number_of_response:1; }","duration":"251.193602ms","start":"2026-05-05T13:15:16.87883Z","end":"2026-05-05T13:15:17.130024Z","steps":["trace[43661948] 'process raft request' (duration: 144.213667ms)","trace[43661948] 'compare' (duration: 106.412128ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:17.351773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.027438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:61 size:566728"} {"level":"info","ts":"2026-05-05T13:15:17.352065Z","caller":"traceutil/trace.go:171","msg":"trace[1014513035] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:61; response_revision:13122; }","duration":"118.334476ms","start":"2026-05-05T13:15:17.233711Z","end":"2026-05-05T13:15:17.352045Z","steps":["trace[1014513035] 'range keys from in-memory index tree' (duration: 117.44552ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:18.258398Z","caller":"traceutil/trace.go:171","msg":"trace[6061029] linearizableReadLoop","detail":"{readStateIndex:13562; appliedIndex:13561; }","duration":"116.066641ms","start":"2026-05-05T13:15:18.142312Z","end":"2026-05-05T13:15:18.258378Z","steps":["trace[6061029] 'read index received' (duration: 115.868456ms)","trace[6061029] 'applied index is now lower than readState.Index' (duration: 197.325µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:18.258517Z","caller":"traceutil/trace.go:171","msg":"trace[99445891] transaction","detail":"{read_only:false; response_revision:13126; number_of_response:1; }","duration":"126.048923ms","start":"2026-05-05T13:15:18.132441Z","end":"2026-05-05T13:15:18.25849Z","steps":["trace[99445891] 'process raft request' (duration: 125.814657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:18.258869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.542905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:16 size:151588"} {"level":"info","ts":"2026-05-05T13:15:18.258929Z","caller":"traceutil/trace.go:171","msg":"trace[1886836937] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:16; response_revision:13126; }","duration":"116.633178ms","start":"2026-05-05T13:15:18.142281Z","end":"2026-05-05T13:15:18.258914Z","steps":["trace[1886836937] 'agreement among raft nodes before linearized reading' (duration: 116.213555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:18.983794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.022046ms","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-05-05T13:15:18.983902Z","caller":"traceutil/trace.go:171","msg":"trace[899637597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13128; }","duration":"298.16986ms","start":"2026-05-05T13:15:18.685699Z","end":"2026-05-05T13:15:18.983869Z","steps":["trace[899637597] 'range keys from in-memory index tree' (duration: 297.81799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:18.983973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.962242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:18.984048Z","caller":"traceutil/trace.go:171","msg":"trace[1704886748] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:13128; }","duration":"125.086676ms","start":"2026-05-05T13:15:18.858944Z","end":"2026-05-05T13:15:18.98403Z","steps":["trace[1704886748] 'count revisions from in-memory index tree' (duration: 124.87096ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:19.36142Z","caller":"traceutil/trace.go:171","msg":"trace[1814810400] transaction","detail":"{read_only:false; response_revision:13129; number_of_response:1; }","duration":"373.550821ms","start":"2026-05-05T13:15:18.987845Z","end":"2026-05-05T13:15:19.361395Z","steps":["trace[1814810400] 'process raft request' (duration: 373.349446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:19.361586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:18.987813Z","time spent":"373.690945ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:15:19.738643Z","caller":"traceutil/trace.go:171","msg":"trace[860977879] transaction","detail":"{read_only:false; response_revision:13130; number_of_response:1; }","duration":"749.910803ms","start":"2026-05-05T13:15:18.988706Z","end":"2026-05-05T13:15:19.738617Z","steps":["trace[860977879] 'process raft request' (duration: 749.689027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:19.738833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:18.98869Z","time spent":"750.061807ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:15:19.738847Z","caller":"traceutil/trace.go:171","msg":"trace[2025601435] linearizableReadLoop","detail":"{readStateIndex:13566; appliedIndex:13564; }","duration":"748.661617ms","start":"2026-05-05T13:15:18.990162Z","end":"2026-05-05T13:15:19.738823Z","steps":["trace[2025601435] 'read index received' (duration: 371.085501ms)","trace[2025601435] 'applied index is now lower than readState.Index' (duration: 377.574946ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:19.738913Z","caller":"traceutil/trace.go:171","msg":"trace[1708488284] transaction","detail":"{read_only:false; response_revision:13131; number_of_response:1; }","duration":"603.894904ms","start":"2026-05-05T13:15:19.134985Z","end":"2026-05-05T13:15:19.73888Z","steps":["trace[1708488284] 'process raft request' (duration: 603.594536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:19.739097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:19.13496Z","time spent":"604.027868ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:15:19.739148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.908654ms","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-05-05T13:15:19.739219Z","caller":"traceutil/trace.go:171","msg":"trace[786908349] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13131; }","duration":"506.025697ms","start":"2026-05-05T13:15:19.233176Z","end":"2026-05-05T13:15:19.739202Z","steps":["trace[786908349] 'agreement among raft nodes before linearized reading' (duration: 505.836022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:19.739229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.056588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-05-05T13:15:19.739276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:19.23316Z","time spent":"506.10265ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T13:15:19.739281Z","caller":"traceutil/trace.go:171","msg":"trace[160227930] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13131; }","duration":"749.12475ms","start":"2026-05-05T13:15:18.990141Z","end":"2026-05-05T13:15:19.739266Z","steps":["trace[160227930] 'agreement among raft nodes before linearized reading' (duration: 748.808411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:19.739453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:18.990135Z","time spent":"749.305045ms","remote":"127.0.0.1:33822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-05T13:15:19.739464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.708642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sr4kw\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-05-05T13:15:19.739498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.697766ms","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-05-05T13:15:19.739583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.107984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T13:15:19.739626Z","caller":"traceutil/trace.go:171","msg":"trace[368983749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13131; }","duration":"347.186696ms","start":"2026-05-05T13:15:19.39243Z","end":"2026-05-05T13:15:19.739617Z","steps":["trace[368983749] 'agreement among raft nodes before linearized reading' (duration: 347.096273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:19.739646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.703688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:19.739698Z","caller":"traceutil/trace.go:171","msg":"trace[1141649463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13131; }","duration":"215.778631ms","start":"2026-05-05T13:15:19.523907Z","end":"2026-05-05T13:15:19.739686Z","steps":["trace[1141649463] 'agreement among raft nodes before linearized reading' (duration: 215.700019ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:19.739579Z","caller":"traceutil/trace.go:171","msg":"trace[105269253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13131; }","duration":"315.787609ms","start":"2026-05-05T13:15:19.423755Z","end":"2026-05-05T13:15:19.739542Z","steps":["trace[105269253] 'agreement among raft nodes before linearized reading' (duration: 315.567253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:19.739723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.809297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:19.739779Z","caller":"traceutil/trace.go:171","msg":"trace[1305285883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13131; }","duration":"229.89449ms","start":"2026-05-05T13:15:19.509868Z","end":"2026-05-05T13:15:19.739763Z","steps":["trace[1305285883] 'agreement among raft nodes before linearized reading' (duration: 229.802477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:19.739786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:19.423739Z","time spent":"316.014345ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T13:15:19.739658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:19.392401Z","time spent":"347.250018ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T13:15:19.739506Z","caller":"traceutil/trace.go:171","msg":"trace[93483868] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sr4kw; range_end:; response_count:1; response_revision:13131; }","duration":"375.754464ms","start":"2026-05-05T13:15:19.36374Z","end":"2026-05-05T13:15:19.739494Z","steps":["trace[93483868] 'agreement among raft nodes before linearized reading' (duration: 375.593229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:19.741331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:19.363669Z","time spent":"377.634387ms","remote":"127.0.0.1:33822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-sr4kw\" "} {"level":"info","ts":"2026-05-05T13:15:19.881133Z","caller":"traceutil/trace.go:171","msg":"trace[1925801759] transaction","detail":"{read_only:false; response_revision:13133; number_of_response:1; }","duration":"133.888465ms","start":"2026-05-05T13:15:19.747217Z","end":"2026-05-05T13:15:19.881105Z","steps":["trace[1925801759] 'process raft request' (duration: 65.164932ms)","trace[1925801759] 'compare' (duration: 68.544178ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:19.881533Z","caller":"traceutil/trace.go:171","msg":"trace[433084282] transaction","detail":"{read_only:false; response_revision:13134; number_of_response:1; }","duration":"133.939597ms","start":"2026-05-05T13:15:19.747566Z","end":"2026-05-05T13:15:19.881506Z","steps":["trace[433084282] 'process raft request' (duration: 133.484274ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:20.574103Z","caller":"traceutil/trace.go:171","msg":"trace[1681905257] transaction","detail":"{read_only:false; response_revision:13137; number_of_response:1; }","duration":"103.494337ms","start":"2026-05-05T13:15:20.470588Z","end":"2026-05-05T13:15:20.574082Z","steps":["trace[1681905257] 'process raft request' (duration: 103.352773ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:21.854577Z","caller":"traceutil/trace.go:171","msg":"trace[301185067] linearizableReadLoop","detail":"{readStateIndex:13577; appliedIndex:13576; }","duration":"108.152529ms","start":"2026-05-05T13:15:21.746398Z","end":"2026-05-05T13:15:21.85455Z","steps":["trace[301185067] 'read index received' (duration: 107.931732ms)","trace[301185067] 'applied index is now lower than readState.Index' (duration: 219.707µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:21.854724Z","caller":"traceutil/trace.go:171","msg":"trace[1920961517] transaction","detail":"{read_only:false; response_revision:13141; number_of_response:1; }","duration":"108.692183ms","start":"2026-05-05T13:15:21.745917Z","end":"2026-05-05T13:15:21.854609Z","steps":["trace[1920961517] 'process raft request' (duration: 108.486087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:21.854912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.500228ms","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-05-05T13:15:21.855107Z","caller":"traceutil/trace.go:171","msg":"trace[506577916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13141; }","duration":"108.609671ms","start":"2026-05-05T13:15:21.746353Z","end":"2026-05-05T13:15:21.854963Z","steps":["trace[506577916] 'agreement among raft nodes before linearized reading' (duration: 108.362034ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:22.109488Z","caller":"traceutil/trace.go:171","msg":"trace[674832160] transaction","detail":"{read_only:false; response_revision:13142; number_of_response:1; }","duration":"250.201014ms","start":"2026-05-05T13:15:21.85927Z","end":"2026-05-05T13:15:22.109471Z","steps":["trace[674832160] 'process raft request' (duration: 250.096131ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:22.118171Z","caller":"traceutil/trace.go:171","msg":"trace[951380166] linearizableReadLoop","detail":"{readStateIndex:13579; appliedIndex:13577; }","duration":"258.247671ms","start":"2026-05-05T13:15:21.859903Z","end":"2026-05-05T13:15:22.118151Z","steps":["trace[951380166] 'read index received' (duration: 249.516875ms)","trace[951380166] 'applied index is now lower than readState.Index' (duration: 8.730046ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:22.118291Z","caller":"traceutil/trace.go:171","msg":"trace[965843033] transaction","detail":"{read_only:false; response_revision:13143; number_of_response:1; }","duration":"258.715675ms","start":"2026-05-05T13:15:21.859554Z","end":"2026-05-05T13:15:22.11827Z","steps":["trace[965843033] 'process raft request' (duration: 258.496498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:22.118388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.838813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T13:15:22.118412Z","caller":"traceutil/trace.go:171","msg":"trace[48750907] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13143; }","duration":"231.886935ms","start":"2026-05-05T13:15:21.886519Z","end":"2026-05-05T13:15:22.118406Z","steps":["trace[48750907] 'agreement among raft nodes before linearized reading' (duration: 231.794313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:22.118408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.686839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T13:15:22.118493Z","caller":"traceutil/trace.go:171","msg":"trace[878084985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13143; }","duration":"232.783721ms","start":"2026-05-05T13:15:21.885657Z","end":"2026-05-05T13:15:22.118441Z","steps":["trace[878084985] 'agreement among raft nodes before linearized reading' (duration: 232.641377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:22.11935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.433615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:61 size:566728"} {"level":"info","ts":"2026-05-05T13:15:22.119415Z","caller":"traceutil/trace.go:171","msg":"trace[1848664106] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:61; response_revision:13143; }","duration":"259.526078ms","start":"2026-05-05T13:15:21.859874Z","end":"2026-05-05T13:15:22.1194Z","steps":["trace[1848664106] 'agreement among raft nodes before linearized reading' (duration: 258.385375ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:22.754432Z","caller":"traceutil/trace.go:171","msg":"trace[143149978] linearizableReadLoop","detail":"{readStateIndex:13584; appliedIndex:13582; }","duration":"149.610249ms","start":"2026-05-05T13:15:22.604792Z","end":"2026-05-05T13:15:22.754402Z","steps":["trace[143149978] 'read index received' (duration: 10.204428ms)","trace[143149978] 'applied index is now lower than readState.Index' (duration: 139.404881ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:22.754707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.880667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-user\" ","response":"range_response_count:1 size:7433"} {"level":"info","ts":"2026-05-05T13:15:22.754788Z","caller":"traceutil/trace.go:171","msg":"trace[1581506045] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-user; range_end:; response_count:1; response_revision:13148; }","duration":"150.032432ms","start":"2026-05-05T13:15:22.604741Z","end":"2026-05-05T13:15:22.754773Z","steps":["trace[1581506045] 'agreement among raft nodes before linearized reading' (duration: 149.757694ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:22.754527Z","caller":"traceutil/trace.go:171","msg":"trace[488715594] transaction","detail":"{read_only:false; response_revision:13148; number_of_response:1; }","duration":"150.715861ms","start":"2026-05-05T13:15:22.603775Z","end":"2026-05-05T13:15:22.754491Z","steps":["trace[488715594] 'process raft request' (duration: 91.955069ms)","trace[488715594] 'compare' (duration: 58.543476ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:24.861055Z","caller":"traceutil/trace.go:171","msg":"trace[776265972] linearizableReadLoop","detail":"{readStateIndex:13595; appliedIndex:13594; }","duration":"100.026217ms","start":"2026-05-05T13:15:24.761012Z","end":"2026-05-05T13:15:24.861038Z","steps":["trace[776265972] 'read index received' (duration: 61.944341ms)","trace[776265972] 'applied index is now lower than readState.Index' (duration: 38.081356ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:24.861111Z","caller":"traceutil/trace.go:171","msg":"trace[2123445721] transaction","detail":"{read_only:false; response_revision:13158; number_of_response:1; }","duration":"100.765259ms","start":"2026-05-05T13:15:24.760304Z","end":"2026-05-05T13:15:24.861069Z","steps":["trace[2123445721] 'process raft request' (duration: 62.646572ms)","trace[2123445721] 'compare' (duration: 37.844439ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:24.861267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.229903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-user\" ","response":"range_response_count:1 size:7433"} {"level":"info","ts":"2026-05-05T13:15:24.861345Z","caller":"traceutil/trace.go:171","msg":"trace[763103030] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-user; range_end:; response_count:1; response_revision:13158; }","duration":"100.337836ms","start":"2026-05-05T13:15:24.760991Z","end":"2026-05-05T13:15:24.861329Z","steps":["trace[763103030] 'agreement among raft nodes before linearized reading' (duration: 100.10908ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:26.066563Z","caller":"traceutil/trace.go:171","msg":"trace[987531767] linearizableReadLoop","detail":"{readStateIndex:13597; appliedIndex:13596; }","duration":"184.087155ms","start":"2026-05-05T13:15:25.88245Z","end":"2026-05-05T13:15:26.066537Z","steps":["trace[987531767] 'read index received' (duration: 183.878409ms)","trace[987531767] 'applied index is now lower than readState.Index' (duration: 207.796µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:26.066619Z","caller":"traceutil/trace.go:171","msg":"trace[1175856591] transaction","detail":"{read_only:false; response_revision:13160; number_of_response:1; }","duration":"186.259236ms","start":"2026-05-05T13:15:25.880326Z","end":"2026-05-05T13:15:26.066586Z","steps":["trace[1175856591] 'process raft request' (duration: 186.056401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:26.066759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.846812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:26.066797Z","caller":"traceutil/trace.go:171","msg":"trace[1821021036] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:13160; }","duration":"134.949795ms","start":"2026-05-05T13:15:25.931835Z","end":"2026-05-05T13:15:26.066785Z","steps":["trace[1821021036] 'agreement among raft nodes before linearized reading' (duration: 134.861172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:26.066822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.331672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:15:26.066906Z","caller":"traceutil/trace.go:171","msg":"trace[1426635738] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:13160; }","duration":"184.471806ms","start":"2026-05-05T13:15:25.882416Z","end":"2026-05-05T13:15:26.066888Z","steps":["trace[1426635738] 'agreement among raft nodes before linearized reading' (duration: 184.233149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:26.499573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.056504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:15:26.499681Z","caller":"traceutil/trace.go:171","msg":"trace[957330948] transaction","detail":"{read_only:false; response_revision:13162; number_of_response:1; }","duration":"238.537904ms","start":"2026-05-05T13:15:26.261123Z","end":"2026-05-05T13:15:26.499661Z","steps":["trace[957330948] 'process raft request' (duration: 135.311125ms)","trace[957330948] 'compare' (duration: 102.948041ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:26.579199Z","caller":"traceutil/trace.go:171","msg":"trace[1820046269] linearizableReadLoop","detail":"{readStateIndex:13601; appliedIndex:13598; }","duration":"317.690863ms","start":"2026-05-05T13:15:26.261487Z","end":"2026-05-05T13:15:26.579177Z","steps":["trace[1820046269] 'read index received' (duration: 135.022777ms)","trace[1820046269] 'applied index is now lower than readState.Index' (duration: 182.667216ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:26.579348Z","caller":"traceutil/trace.go:171","msg":"trace[103088236] transaction","detail":"{read_only:false; response_revision:13163; number_of_response:1; }","duration":"317.899129ms","start":"2026-05-05T13:15:26.261416Z","end":"2026-05-05T13:15:26.579315Z","steps":["trace[103088236] 'process raft request' (duration: 317.600971ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:26.579335Z","caller":"traceutil/trace.go:171","msg":"trace[454443363] transaction","detail":"{read_only:false; response_revision:13164; number_of_response:1; }","duration":"317.867288ms","start":"2026-05-05T13:15:26.261456Z","end":"2026-05-05T13:15:26.579323Z","steps":["trace[454443363] 'process raft request' (duration: 317.679813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:26.579662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:26.261386Z","time spent":"318.093955ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:15:26.579708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:26.261435Z","time spent":"318.141955ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:15:26.579732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.006288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T13:15:26.579857Z","caller":"traceutil/trace.go:171","msg":"trace[1066355375] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13164; }","duration":"158.215244ms","start":"2026-05-05T13:15:26.421611Z","end":"2026-05-05T13:15:26.579827Z","steps":["trace[1066355375] 'agreement among raft nodes before linearized reading' (duration: 157.817133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:26.580569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.040331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:61 size:566728"} {"level":"info","ts":"2026-05-05T13:15:26.580687Z","caller":"traceutil/trace.go:171","msg":"trace[1476193163] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:61; response_revision:13164; }","duration":"319.210816ms","start":"2026-05-05T13:15:26.261453Z","end":"2026-05-05T13:15:26.580664Z","steps":["trace[1476193163] 'agreement among raft nodes before linearized reading' (duration: 318.241429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:26.580762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:26.261437Z","time spent":"319.307308ms","remote":"127.0.0.1:33822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":61,"response size":566752,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-05-05T13:15:26.829146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.38038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:15:26.829766Z","caller":"traceutil/trace.go:171","msg":"trace[1463785916] transaction","detail":"{read_only:false; response_revision:13165; number_of_response:1; }","duration":"244.314877ms","start":"2026-05-05T13:15:26.585407Z","end":"2026-05-05T13:15:26.829722Z","steps":["trace[1463785916] 'process raft request' (duration: 80.207997ms)","trace[1463785916] 'compare' (duration: 162.993629ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:26.829762Z","caller":"traceutil/trace.go:171","msg":"trace[2045295493] transaction","detail":"{read_only:false; response_revision:13166; number_of_response:1; }","duration":"142.977662ms","start":"2026-05-05T13:15:26.68674Z","end":"2026-05-05T13:15:26.829718Z","steps":["trace[2045295493] 'process raft request' (duration: 142.566361ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:27.056758Z","caller":"traceutil/trace.go:171","msg":"trace[625007286] linearizableReadLoop","detail":"{readStateIndex:13604; appliedIndex:13603; }","duration":"190.086145ms","start":"2026-05-05T13:15:26.866644Z","end":"2026-05-05T13:15:27.05673Z","steps":["trace[625007286] 'read index received' (duration: 169.007059ms)","trace[625007286] 'applied index is now lower than readState.Index' (duration: 21.077926ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:27.056789Z","caller":"traceutil/trace.go:171","msg":"trace[1555699880] transaction","detail":"{read_only:false; response_revision:13167; number_of_response:1; }","duration":"212.905589ms","start":"2026-05-05T13:15:26.843852Z","end":"2026-05-05T13:15:27.056758Z","steps":["trace[1555699880] 'process raft request' (duration: 191.792723ms)","trace[1555699880] 'compare' (duration: 20.923811ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:27.057022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.366723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-user\" ","response":"range_response_count:1 size:7433"} {"level":"info","ts":"2026-05-05T13:15:27.057104Z","caller":"traceutil/trace.go:171","msg":"trace[1931014610] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-user; range_end:; response_count:1; response_revision:13167; }","duration":"190.516917ms","start":"2026-05-05T13:15:26.86657Z","end":"2026-05-05T13:15:27.057087Z","steps":["trace[1931014610] 'agreement among raft nodes before linearized reading' (duration: 190.27694ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:27.076564Z","caller":"traceutil/trace.go:171","msg":"trace[1899982449] transaction","detail":"{read_only:false; response_revision:13168; number_of_response:1; }","duration":"208.812213ms","start":"2026-05-05T13:15:26.867714Z","end":"2026-05-05T13:15:27.076526Z","steps":["trace[1899982449] 'process raft request' (duration: 208.602717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:27.625602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.937654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:27.625694Z","caller":"traceutil/trace.go:171","msg":"trace[1336624019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13169; }","duration":"101.095478ms","start":"2026-05-05T13:15:27.52458Z","end":"2026-05-05T13:15:27.625676Z","steps":["trace[1336624019] 'range keys from in-memory index tree' (duration: 100.835141ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:27.93158Z","caller":"traceutil/trace.go:171","msg":"trace[2139444228] transaction","detail":"{read_only:false; response_revision:13171; number_of_response:1; }","duration":"146.953354ms","start":"2026-05-05T13:15:27.784596Z","end":"2026-05-05T13:15:27.931549Z","steps":["trace[2139444228] 'process raft request' (duration: 109.820784ms)","trace[2139444228] 'compare' (duration: 37.003756ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:30.513109Z","caller":"traceutil/trace.go:171","msg":"trace[1411698708] transaction","detail":"{read_only:false; response_revision:13200; number_of_response:1; }","duration":"141.669026ms","start":"2026-05-05T13:15:30.371412Z","end":"2026-05-05T13:15:30.513081Z","steps":["trace[1411698708] 'process raft request' (duration: 135.571263ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:30.966613Z","caller":"traceutil/trace.go:171","msg":"trace[430688424] transaction","detail":"{read_only:false; response_revision:13206; number_of_response:1; }","duration":"110.893335ms","start":"2026-05-05T13:15:30.855674Z","end":"2026-05-05T13:15:30.966567Z","steps":["trace[430688424] 'process raft request' (duration: 103.781703ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:31.921194Z","caller":"traceutil/trace.go:171","msg":"trace[1303944534] transaction","detail":"{read_only:false; response_revision:13213; number_of_response:1; }","duration":"113.13503ms","start":"2026-05-05T13:15:31.808026Z","end":"2026-05-05T13:15:31.921161Z","steps":["trace[1303944534] 'process raft request' (duration: 104.157465ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:33.357368Z","caller":"traceutil/trace.go:171","msg":"trace[1632398733] linearizableReadLoop","detail":"{readStateIndex:13660; appliedIndex:13659; }","duration":"163.612356ms","start":"2026-05-05T13:15:33.193733Z","end":"2026-05-05T13:15:33.357345Z","steps":["trace[1632398733] 'read index received' (duration: 76.071951ms)","trace[1632398733] 'applied index is now lower than readState.Index' (duration: 87.539394ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:33.357428Z","caller":"traceutil/trace.go:171","msg":"trace[484923294] transaction","detail":"{read_only:false; response_revision:13221; number_of_response:1; }","duration":"232.271568ms","start":"2026-05-05T13:15:33.125117Z","end":"2026-05-05T13:15:33.357389Z","steps":["trace[484923294] 'process raft request' (duration: 144.790745ms)","trace[484923294] 'compare' (duration: 87.340479ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:33.357557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.815062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:15:33.357608Z","caller":"traceutil/trace.go:171","msg":"trace[675712348] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:13221; }","duration":"163.917664ms","start":"2026-05-05T13:15:33.193674Z","end":"2026-05-05T13:15:33.357591Z","steps":["trace[675712348] 'agreement among raft nodes before linearized reading' (duration: 163.820892ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:34.060256Z","caller":"traceutil/trace.go:171","msg":"trace[721788314] transaction","detail":"{read_only:false; response_revision:13224; number_of_response:1; }","duration":"190.156247ms","start":"2026-05-05T13:15:33.870059Z","end":"2026-05-05T13:15:34.060215Z","steps":["trace[721788314] 'process raft request' (duration: 168.396071ms)","trace[721788314] 'compare' (duration: 21.573891ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:34.288554Z","caller":"traceutil/trace.go:171","msg":"trace[1240480046] transaction","detail":"{read_only:false; response_revision:13225; number_of_response:1; }","duration":"101.896931ms","start":"2026-05-05T13:15:34.186619Z","end":"2026-05-05T13:15:34.288516Z","steps":["trace[1240480046] 'process raft request' (duration: 101.53322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:35.212348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.969903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:15:35.212619Z","caller":"traceutil/trace.go:171","msg":"trace[461520448] transaction","detail":"{read_only:false; response_revision:13237; number_of_response:1; }","duration":"151.225753ms","start":"2026-05-05T13:15:35.061368Z","end":"2026-05-05T13:15:35.212593Z","steps":["trace[461520448] 'process raft request' (duration: 151.085749ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:35.212684Z","caller":"traceutil/trace.go:171","msg":"trace[462139596] transaction","detail":"{read_only:false; response_revision:13236; number_of_response:1; }","duration":"161.239846ms","start":"2026-05-05T13:15:35.051408Z","end":"2026-05-05T13:15:35.212648Z","steps":["trace[462139596] 'process raft request' (duration: 19.890042ms)","trace[462139596] 'compare' (duration: 140.820799ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:35.460178Z","caller":"traceutil/trace.go:171","msg":"trace[704909507] linearizableReadLoop","detail":"{readStateIndex:13678; appliedIndex:13677; }","duration":"159.444395ms","start":"2026-05-05T13:15:35.300716Z","end":"2026-05-05T13:15:35.46016Z","steps":["trace[704909507] 'read index received' (duration: 159.406444ms)","trace[704909507] 'applied index is now lower than readState.Index' (duration: 37.261µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:35.460322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.59555ms","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-05-05T13:15:35.460344Z","caller":"traceutil/trace.go:171","msg":"trace[1770781562] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13239; }","duration":"159.668062ms","start":"2026-05-05T13:15:35.30067Z","end":"2026-05-05T13:15:35.460338Z","steps":["trace[1770781562] 'agreement among raft nodes before linearized reading' (duration: 159.556619ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:35.460552Z","caller":"traceutil/trace.go:171","msg":"trace[826390735] transaction","detail":"{read_only:false; response_revision:13239; number_of_response:1; }","duration":"172.839404ms","start":"2026-05-05T13:15:35.287684Z","end":"2026-05-05T13:15:35.460524Z","steps":["trace[826390735] 'process raft request' (duration: 172.303329ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:35.636033Z","caller":"traceutil/trace.go:171","msg":"trace[1164022669] linearizableReadLoop","detail":"{readStateIndex:13679; appliedIndex:13678; }","duration":"170.60006ms","start":"2026-05-05T13:15:35.465391Z","end":"2026-05-05T13:15:35.635991Z","steps":["trace[1164022669] 'read index received' (duration: 78.386365ms)","trace[1164022669] 'applied index is now lower than readState.Index' (duration: 92.212775ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:35.636064Z","caller":"traceutil/trace.go:171","msg":"trace[2066149332] transaction","detail":"{read_only:false; response_revision:13240; number_of_response:1; }","duration":"273.709214ms","start":"2026-05-05T13:15:35.362322Z","end":"2026-05-05T13:15:35.636031Z","steps":["trace[2066149332] 'process raft request' (duration: 181.502499ms)","trace[2066149332] 'compare' (duration: 92.04351ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:35.636177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.770015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:15:35.636211Z","caller":"traceutil/trace.go:171","msg":"trace[1925346913] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13240; }","duration":"170.857538ms","start":"2026-05-05T13:15:35.465343Z","end":"2026-05-05T13:15:35.6362Z","steps":["trace[1925346913] 'agreement among raft nodes before linearized reading' (duration: 170.773945ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:36.404595Z","caller":"traceutil/trace.go:171","msg":"trace[377791274] transaction","detail":"{read_only:false; response_revision:13244; number_of_response:1; }","duration":"110.687668ms","start":"2026-05-05T13:15:36.293885Z","end":"2026-05-05T13:15:36.404572Z","steps":["trace[377791274] 'process raft request' (duration: 110.361509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:36.949468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.42581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T13:15:36.949547Z","caller":"traceutil/trace.go:171","msg":"trace[1482752432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13246; }","duration":"121.546074ms","start":"2026-05-05T13:15:36.827982Z","end":"2026-05-05T13:15:36.949528Z","steps":["trace[1482752432] 'range keys from in-memory index tree' (duration: 121.264486ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:37.106339Z","caller":"traceutil/trace.go:171","msg":"trace[1004437666] transaction","detail":"{read_only:false; response_revision:13250; number_of_response:1; }","duration":"139.11549ms","start":"2026-05-05T13:15:36.967202Z","end":"2026-05-05T13:15:37.106317Z","steps":["trace[1004437666] 'process raft request' (duration: 139.072318ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:37.106714Z","caller":"traceutil/trace.go:171","msg":"trace[750577740] transaction","detail":"{read_only:false; response_revision:13248; number_of_response:1; }","duration":"152.88499ms","start":"2026-05-05T13:15:36.953774Z","end":"2026-05-05T13:15:37.106659Z","steps":["trace[750577740] 'process raft request' (duration: 129.789187ms)","trace[750577740] 'compare' (duration: 22.270399ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:37.106982Z","caller":"traceutil/trace.go:171","msg":"trace[2038992719] transaction","detail":"{read_only:false; response_revision:13249; number_of_response:1; }","duration":"141.763796ms","start":"2026-05-05T13:15:36.965208Z","end":"2026-05-05T13:15:37.106972Z","steps":["trace[2038992719] 'process raft request' (duration: 140.996924ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:37.182136Z","caller":"traceutil/trace.go:171","msg":"trace[779496055] transaction","detail":"{read_only:false; response_revision:13251; number_of_response:1; }","duration":"146.264823ms","start":"2026-05-05T13:15:37.035844Z","end":"2026-05-05T13:15:37.182109Z","steps":["trace[779496055] 'process raft request' (duration: 146.140679ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:37.770666Z","caller":"traceutil/trace.go:171","msg":"trace[640462149] transaction","detail":"{read_only:false; response_revision:13254; number_of_response:1; }","duration":"120.299169ms","start":"2026-05-05T13:15:37.650336Z","end":"2026-05-05T13:15:37.770635Z","steps":["trace[640462149] 'process raft request' (duration: 90.092175ms)","trace[640462149] 'compare' (duration: 30.050619ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:38.223646Z","caller":"traceutil/trace.go:171","msg":"trace[601501815] transaction","detail":"{read_only:false; response_revision:13256; number_of_response:1; }","duration":"105.968064ms","start":"2026-05-05T13:15:38.117635Z","end":"2026-05-05T13:15:38.223603Z","steps":["trace[601501815] 'process raft request' (duration: 105.79876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:39.096111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.830076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:15:39.096196Z","caller":"traceutil/trace.go:171","msg":"trace[1046035687] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:13258; }","duration":"159.96495ms","start":"2026-05-05T13:15:38.936214Z","end":"2026-05-05T13:15:39.096179Z","steps":["trace[1046035687] 'count revisions from in-memory index tree' (duration: 159.733433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:39.096231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.870951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" ","response":"range_response_count:1 size:12757"} {"level":"info","ts":"2026-05-05T13:15:39.096295Z","caller":"traceutil/trace.go:171","msg":"trace[107322597] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-endpoints; range_end:; response_count:1; response_revision:13258; }","duration":"455.986024ms","start":"2026-05-05T13:15:38.64029Z","end":"2026-05-05T13:15:39.096276Z","steps":["trace[107322597] 'range keys from in-memory index tree' (duration: 455.678505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:39.096309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.282471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T13:15:39.096349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:38.640261Z","time spent":"456.074957ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":12781,"request content":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" "} {"level":"info","ts":"2026-05-05T13:15:39.096395Z","caller":"traceutil/trace.go:171","msg":"trace[1168059437] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:13258; }","duration":"268.415214ms","start":"2026-05-05T13:15:38.82796Z","end":"2026-05-05T13:15:39.096375Z","steps":["trace[1168059437] 'count revisions from in-memory index tree' (duration: 268.204848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:39.096398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.2262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T13:15:39.096463Z","caller":"traceutil/trace.go:171","msg":"trace[2009111587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13258; }","duration":"359.332283ms","start":"2026-05-05T13:15:38.737114Z","end":"2026-05-05T13:15:39.096446Z","steps":["trace[2009111587] 'range keys from in-memory index tree' (duration: 359.078285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:39.096505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:38.737096Z","time spent":"359.396654ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T13:15:39.096621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.078556ms","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-05-05T13:15:39.09677Z","caller":"traceutil/trace.go:171","msg":"trace[750127270] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:13258; }","duration":"416.288203ms","start":"2026-05-05T13:15:38.680427Z","end":"2026-05-05T13:15:39.096715Z","steps":["trace[750127270] 'count revisions from in-memory index tree' (duration: 415.870541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:39.096903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:15:38.680404Z","time spent":"416.464417ms","remote":"127.0.0.1:51656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-05-05T13:15:39.341437Z","caller":"traceutil/trace.go:171","msg":"trace[2015356694] transaction","detail":"{read_only:false; response_revision:13259; number_of_response:1; }","duration":"240.503185ms","start":"2026-05-05T13:15:39.100908Z","end":"2026-05-05T13:15:39.341411Z","steps":["trace[2015356694] 'process raft request' (duration: 240.355751ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:39.380153Z","caller":"traceutil/trace.go:171","msg":"trace[882957613] linearizableReadLoop","detail":"{readStateIndex:13700; appliedIndex:13699; }","duration":"269.580207ms","start":"2026-05-05T13:15:39.110552Z","end":"2026-05-05T13:15:39.380132Z","steps":["trace[882957613] 'read index received' (duration: 230.881213ms)","trace[882957613] 'applied index is now lower than readState.Index' (duration: 38.697974ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:39.380252Z","caller":"traceutil/trace.go:171","msg":"trace[1100192301] transaction","detail":"{read_only:false; response_revision:13260; number_of_response:1; }","duration":"278.691894ms","start":"2026-05-05T13:15:39.101505Z","end":"2026-05-05T13:15:39.380196Z","steps":["trace[1100192301] 'process raft request' (duration: 278.473228ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:39.380285Z","caller":"traceutil/trace.go:171","msg":"trace[1758973711] transaction","detail":"{read_only:false; response_revision:13261; number_of_response:1; }","duration":"268.436195ms","start":"2026-05-05T13:15:39.111808Z","end":"2026-05-05T13:15:39.380244Z","steps":["trace[1758973711] 'process raft request' (duration: 268.296791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:39.380441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.865865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T13:15:39.380494Z","caller":"traceutil/trace.go:171","msg":"trace[610806198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13261; }","duration":"269.935337ms","start":"2026-05-05T13:15:39.110545Z","end":"2026-05-05T13:15:39.38048Z","steps":["trace[610806198] 'agreement among raft nodes before linearized reading' (duration: 269.70281ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:39.488738Z","caller":"traceutil/trace.go:171","msg":"trace[728313294] transaction","detail":"{read_only:false; response_revision:13262; number_of_response:1; }","duration":"103.136384ms","start":"2026-05-05T13:15:39.38557Z","end":"2026-05-05T13:15:39.488706Z","steps":["trace[728313294] 'process raft request' (duration: 41.247765ms)","trace[728313294] 'compare' (duration: 61.656192ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:39.489047Z","caller":"traceutil/trace.go:171","msg":"trace[2040473218] transaction","detail":"{read_only:false; response_revision:13263; number_of_response:1; }","duration":"101.746274ms","start":"2026-05-05T13:15:39.387149Z","end":"2026-05-05T13:15:39.488895Z","steps":["trace[2040473218] 'process raft request' (duration: 101.509527ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:40.560977Z","caller":"traceutil/trace.go:171","msg":"trace[24272478] linearizableReadLoop","detail":"{readStateIndex:13706; appliedIndex:13705; }","duration":"104.274877ms","start":"2026-05-05T13:15:40.456676Z","end":"2026-05-05T13:15:40.56095Z","steps":["trace[24272478] 'read index received' (duration: 103.990249ms)","trace[24272478] 'applied index is now lower than readState.Index' (duration: 283.318µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:40.561074Z","caller":"traceutil/trace.go:171","msg":"trace[522363828] transaction","detail":"{read_only:false; response_revision:13266; number_of_response:1; }","duration":"111.256884ms","start":"2026-05-05T13:15:40.449779Z","end":"2026-05-05T13:15:40.561035Z","steps":["trace[522363828] 'process raft request' (duration: 110.990137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:15:40.561382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.684159ms","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-05-05T13:15:40.561462Z","caller":"traceutil/trace.go:171","msg":"trace[1282961557] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:13266; }","duration":"104.788582ms","start":"2026-05-05T13:15:40.456653Z","end":"2026-05-05T13:15:40.561442Z","steps":["trace[1282961557] 'agreement among raft nodes before linearized reading' (duration: 104.424251ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:40.719018Z","caller":"traceutil/trace.go:171","msg":"trace[1219096652] transaction","detail":"{read_only:false; response_revision:13267; number_of_response:1; }","duration":"121.176823ms","start":"2026-05-05T13:15:40.597805Z","end":"2026-05-05T13:15:40.718982Z","steps":["trace[1219096652] 'process raft request' (duration: 65.678165ms)","trace[1219096652] 'compare' (duration: 55.077607ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:15:43.647443Z","caller":"traceutil/trace.go:171","msg":"trace[398296996] linearizableReadLoop","detail":"{readStateIndex:13723; appliedIndex:13722; }","duration":"106.680545ms","start":"2026-05-05T13:15:43.540747Z","end":"2026-05-05T13:15:43.647428Z","steps":["trace[398296996] 'read index received' (duration: 106.327495ms)","trace[398296996] 'applied index is now lower than readState.Index' (duration: 352.49µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:15:43.647578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.818718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T13:15:43.647603Z","caller":"traceutil/trace.go:171","msg":"trace[1154815571] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13282; }","duration":"106.8798ms","start":"2026-05-05T13:15:43.540715Z","end":"2026-05-05T13:15:43.647594Z","steps":["trace[1154815571] 'agreement among raft nodes before linearized reading' (duration: 106.771367ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:43.647739Z","caller":"traceutil/trace.go:171","msg":"trace[1179569443] transaction","detail":"{read_only:false; response_revision:13282; number_of_response:1; }","duration":"118.726495ms","start":"2026-05-05T13:15:43.529003Z","end":"2026-05-05T13:15:43.647729Z","steps":["trace[1179569443] 'process raft request' (duration: 118.135307ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:15:55.675489Z","caller":"traceutil/trace.go:171","msg":"trace[325684091] transaction","detail":"{read_only:false; response_revision:13386; number_of_response:1; }","duration":"138.656928ms","start":"2026-05-05T13:15:55.536814Z","end":"2026-05-05T13:15:55.675471Z","steps":["trace[325684091] 'process raft request' (duration: 138.550385ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:16:34.909525Z","caller":"traceutil/trace.go:171","msg":"trace[2079441231] transaction","detail":"{read_only:false; response_revision:13592; number_of_response:1; }","duration":"131.837085ms","start":"2026-05-05T13:16:34.777664Z","end":"2026-05-05T13:16:34.909501Z","steps":["trace[2079441231] 'process raft request' (duration: 131.683241ms)"],"step_count":1}