{"level":"warn","ts":"2026-08-12T11:03:46.518805Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T11:03:46.518932Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.137: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.19.213.137:2380","--initial-cluster=instance=https://199.19.213.137:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.137:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.137: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-08-12T11:03:46.51902Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T11:03:46.519037Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.137:2380"]} {"level":"info","ts":"2026-08-12T11:03:46.519056Z","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-08-12T11:03:46.519812Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"]} {"level":"info","ts":"2026-08-12T11:03:46.519912Z","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.19.213.137:2380"],"listen-peer-urls":["https://199.19.213.137:2380"],"advertise-client-urls":["https://199.19.213.137:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.137: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-08-12T11:03:46.524246Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.072975ms"} {"level":"info","ts":"2026-08-12T11:03:46.532269Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2ba8e3c2b0588654","cluster-id":"97694c3f9947e4ca"} {"level":"info","ts":"2026-08-12T11:03:46.532358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T11:03:46.532382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became follower at term 0"} {"level":"info","ts":"2026-08-12T11:03:46.532399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2ba8e3c2b0588654 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T11:03:46.532413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became follower at term 1"} {"level":"info","ts":"2026-08-12T11:03:46.532477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=(3146014765040043604)"} {"level":"warn","ts":"2026-08-12T11:03:46.538992Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T11:03:46.540354Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T11:03:46.541662Z","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-08-12T11:03:46.543763Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2ba8e3c2b0588654","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T11:03:46.543938Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2ba8e3c2b0588654","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T11:03:46.544165Z","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-08-12T11:03:46.544252Z","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-08-12T11:03:46.54427Z","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-08-12T11:03:46.544901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=(3146014765040043604)"} {"level":"info","ts":"2026-08-12T11:03:46.545011Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97694c3f9947e4ca","local-member-id":"2ba8e3c2b0588654","added-peer-id":"2ba8e3c2b0588654","added-peer-peer-urls":["https://199.19.213.137:2380"]} {"level":"info","ts":"2026-08-12T11:03:46.551931Z","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-08-12T11:03:46.552105Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.137:2380"} {"level":"info","ts":"2026-08-12T11:03:46.552134Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.137:2380"} {"level":"info","ts":"2026-08-12T11:03:46.552382Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2ba8e3c2b0588654","initial-advertise-peer-urls":["https://199.19.213.137:2380"],"listen-peer-urls":["https://199.19.213.137:2380"],"advertise-client-urls":["https://199.19.213.137:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T11:03:46.552477Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T11:03:46.732687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T11:03:46.732782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T11:03:46.732803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 received MsgPreVoteResp from 2ba8e3c2b0588654 at term 1"} {"level":"info","ts":"2026-08-12T11:03:46.732821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became candidate at term 2"} {"level":"info","ts":"2026-08-12T11:03:46.732844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 received MsgVoteResp from 2ba8e3c2b0588654 at term 2"} {"level":"info","ts":"2026-08-12T11:03:46.732854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became leader at term 2"} {"level":"info","ts":"2026-08-12T11:03:46.732866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2ba8e3c2b0588654 elected leader 2ba8e3c2b0588654 at term 2"} {"level":"info","ts":"2026-08-12T11:03:46.734901Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:03:46.739492Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T11:03:46.739485Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2ba8e3c2b0588654","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.137:2379]}","request-path":"/0/members/2ba8e3c2b0588654/attributes","cluster-id":"97694c3f9947e4ca","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T11:03:46.739531Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T11:03:46.739937Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T11:03:46.739971Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T11:03:46.741586Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T11:03:46.741974Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.137:2379"} {"level":"info","ts":"2026-08-12T11:03:46.74231Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97694c3f9947e4ca","local-member-id":"2ba8e3c2b0588654","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:03:46.742433Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:03:46.742481Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:04:24.669531Z","caller":"traceutil/trace.go:171","msg":"trace[246558915] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"121.809395ms","start":"2026-08-12T11:04:24.54769Z","end":"2026-08-12T11:04:24.6695Z","steps":["trace[246558915] 'process raft request' (duration: 121.655451ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:24.680293Z","caller":"traceutil/trace.go:171","msg":"trace[1727453195] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"130.953403ms","start":"2026-08-12T11:04:24.549296Z","end":"2026-08-12T11:04:24.68025Z","steps":["trace[1727453195] 'process raft request' (duration: 130.754087ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:24.680599Z","caller":"traceutil/trace.go:171","msg":"trace[1728143749] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"122.748453ms","start":"2026-08-12T11:04:24.557828Z","end":"2026-08-12T11:04:24.680576Z","steps":["trace[1728143749] 'process raft request' (duration: 122.440154ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:24.680791Z","caller":"traceutil/trace.go:171","msg":"trace[1405198348] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"123.3958ms","start":"2026-08-12T11:04:24.557388Z","end":"2026-08-12T11:04:24.680783Z","steps":["trace[1405198348] 'process raft request' (duration: 122.844365ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:24.680834Z","caller":"traceutil/trace.go:171","msg":"trace[458621297] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"131.318252ms","start":"2026-08-12T11:04:24.549468Z","end":"2026-08-12T11:04:24.680786Z","steps":["trace[458621297] 'process raft request' (duration: 130.722936ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:24.680951Z","caller":"traceutil/trace.go:171","msg":"trace[1032533720] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"121.153907ms","start":"2026-08-12T11:04:24.559779Z","end":"2026-08-12T11:04:24.680933Z","steps":["trace[1032533720] 'process raft request' (duration: 120.525409ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:24.681016Z","caller":"traceutil/trace.go:171","msg":"trace[1294614135] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"120.111579ms","start":"2026-08-12T11:04:24.560894Z","end":"2026-08-12T11:04:24.681005Z","steps":["trace[1294614135] 'process raft request' (duration: 119.45063ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:24.681041Z","caller":"traceutil/trace.go:171","msg":"trace[590232332] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"116.224348ms","start":"2026-08-12T11:04:24.564806Z","end":"2026-08-12T11:04:24.68103Z","steps":["trace[590232332] 'process raft request' (duration: 115.57613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:30.949847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.052294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:04:30.950491Z","caller":"traceutil/trace.go:171","msg":"trace[1378194501] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"231.230414ms","start":"2026-08-12T11:04:30.718766Z","end":"2026-08-12T11:04:30.949996Z","steps":["trace[1378194501] 'process raft request' (duration: 114.278804ms)","trace[1378194501] 'compare' (duration: 115.902341ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:31.043643Z","caller":"traceutil/trace.go:171","msg":"trace[1237616627] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"186.204221ms","start":"2026-08-12T11:04:30.857419Z","end":"2026-08-12T11:04:31.043624Z","steps":["trace[1237616627] 'read index received' (duration: 185.533582ms)","trace[1237616627] 'applied index is now lower than readState.Index' (duration: 669.639µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:31.043806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.379947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:04:31.043785Z","caller":"traceutil/trace.go:171","msg":"trace[1048436882] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"318.614064ms","start":"2026-08-12T11:04:30.725106Z","end":"2026-08-12T11:04:31.04372Z","steps":["trace[1048436882] 'process raft request' (duration: 318.088898ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:31.043888Z","caller":"traceutil/trace.go:171","msg":"trace[1478419192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"186.463179ms","start":"2026-08-12T11:04:30.857366Z","end":"2026-08-12T11:04:31.043829Z","steps":["trace[1478419192] 'agreement among raft nodes before linearized reading' (duration: 186.369716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:31.044015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:30.725081Z","time spent":"318.81596ms","remote":"127.0.0.1:45928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:04:31.044103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.248251ms","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-08-12T11:04:31.044233Z","caller":"traceutil/trace.go:171","msg":"trace[1766679604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"151.455336ms","start":"2026-08-12T11:04:30.892747Z","end":"2026-08-12T11:04:31.044202Z","steps":["trace[1766679604] 'agreement among raft nodes before linearized reading' (duration: 151.075646ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:31.536873Z","caller":"traceutil/trace.go:171","msg":"trace[204844754] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"421.825909ms","start":"2026-08-12T11:04:31.114992Z","end":"2026-08-12T11:04:31.536818Z","steps":["trace[204844754] 'process raft request' (duration: 418.168956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:31.537067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:31.114962Z","time spent":"422.022664ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T11:04:45.320644Z","caller":"traceutil/trace.go:171","msg":"trace[500750710] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"287.006237ms","start":"2026-08-12T11:04:45.033595Z","end":"2026-08-12T11:04:45.320602Z","steps":["trace[500750710] 'process raft request' (duration: 230.073561ms)","trace[500750710] 'compare' (duration: 56.629668ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:45.322298Z","caller":"traceutil/trace.go:171","msg":"trace[1718440327] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:779; }","duration":"177.831227ms","start":"2026-08-12T11:04:45.144429Z","end":"2026-08-12T11:04:45.32226Z","steps":["trace[1718440327] 'read index received' (duration: 119.162252ms)","trace[1718440327] 'applied index is now lower than readState.Index' (duration: 58.667315ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:45.322542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.085894ms","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-08-12T11:04:45.322611Z","caller":"traceutil/trace.go:171","msg":"trace[551369810] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"178.206937ms","start":"2026-08-12T11:04:45.144389Z","end":"2026-08-12T11:04:45.322596Z","steps":["trace[551369810] 'agreement among raft nodes before linearized reading' (duration: 177.991361ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:02.600801Z","caller":"traceutil/trace.go:171","msg":"trace[15984451] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"332.824511ms","start":"2026-08-12T11:06:02.267934Z","end":"2026-08-12T11:06:02.600758Z","steps":["trace[15984451] 'process raft request' (duration: 332.681907ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:02.60083Z","caller":"traceutil/trace.go:171","msg":"trace[382367072] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"333.154202ms","start":"2026-08-12T11:06:02.267633Z","end":"2026-08-12T11:06:02.600788Z","steps":["trace[382367072] 'process raft request' (duration: 300.271531ms)","trace[382367072] 'compare' (duration: 31.810241ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:06:02.601232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:02.267912Z","time spent":"333.211243ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:06:02.601248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:02.267626Z","time spent":"333.495712ms","remote":"127.0.0.1:45984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:06:02.601338Z","caller":"traceutil/trace.go:171","msg":"trace[1547058396] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"331.712672ms","start":"2026-08-12T11:06:02.269604Z","end":"2026-08-12T11:06:02.601317Z","steps":["trace[1547058396] 'process raft request' (duration: 331.126054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:02.601415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:02.269591Z","time spent":"331.783733ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:06:02.601538Z","caller":"traceutil/trace.go:171","msg":"trace[728282771] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"328.79638ms","start":"2026-08-12T11:06:02.272705Z","end":"2026-08-12T11:06:02.601501Z","steps":["trace[728282771] 'process raft request' (duration: 328.533103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:02.601772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:02.272689Z","time spent":"328.973965ms","remote":"127.0.0.1:46012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:06:02.984487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.803695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:06:02.984606Z","caller":"traceutil/trace.go:171","msg":"trace[1695682571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1830; }","duration":"132.005571ms","start":"2026-08-12T11:06:02.852568Z","end":"2026-08-12T11:06:02.984574Z","steps":["trace[1695682571] 'range keys from in-memory index tree' (duration: 131.666221ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:47.914969Z","caller":"traceutil/trace.go:171","msg":"trace[225858868] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"156.179529ms","start":"2026-08-12T11:06:47.758744Z","end":"2026-08-12T11:06:47.914924Z","steps":["trace[225858868] 'process raft request' (duration: 144.114521ms)","trace[225858868] 'compare' (duration: 11.632516ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:06:48.399013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.930237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:06:48.399192Z","caller":"traceutil/trace.go:171","msg":"trace[1009947087] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"130.794828ms","start":"2026-08-12T11:06:48.268339Z","end":"2026-08-12T11:06:48.399133Z","steps":["trace[1009947087] 'process raft request' (duration: 12.615014ms)","trace[1009947087] 'compare' (duration: 117.764532ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:06:53.880697Z","caller":"traceutil/trace.go:171","msg":"trace[868568391] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"117.630229ms","start":"2026-08-12T11:06:53.76303Z","end":"2026-08-12T11:06:53.88066Z","steps":["trace[868568391] 'process raft request' (duration: 117.078982ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:54.076328Z","caller":"traceutil/trace.go:171","msg":"trace[545520039] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2232; }","duration":"137.753662ms","start":"2026-08-12T11:06:53.938549Z","end":"2026-08-12T11:06:54.076303Z","steps":["trace[545520039] 'read index received' (duration: 89.925921ms)","trace[545520039] 'applied index is now lower than readState.Index' (duration: 47.826311ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:06:54.07641Z","caller":"traceutil/trace.go:171","msg":"trace[591635755] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"139.688896ms","start":"2026-08-12T11:06:53.936688Z","end":"2026-08-12T11:06:54.076377Z","steps":["trace[591635755] 'process raft request' (duration: 91.727182ms)","trace[591635755] 'compare' (duration: 47.747849ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:06:54.076651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.066542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:06:54.076714Z","caller":"traceutil/trace.go:171","msg":"trace[412305256] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2154; }","duration":"138.183875ms","start":"2026-08-12T11:06:53.938516Z","end":"2026-08-12T11:06:54.0767Z","steps":["trace[412305256] 'agreement among raft nodes before linearized reading' (duration: 137.969118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:54.442511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.839494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:06:54.442707Z","caller":"traceutil/trace.go:171","msg":"trace[1974629712] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2233; }","duration":"343.687867ms","start":"2026-08-12T11:06:54.099001Z","end":"2026-08-12T11:06:54.442688Z","steps":["trace[1974629712] 'read index received' (duration: 133.577686ms)","trace[1974629712] 'applied index is now lower than readState.Index' (duration: 210.109011ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:06:54.442742Z","caller":"traceutil/trace.go:171","msg":"trace[702628286] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"360.56953ms","start":"2026-08-12T11:06:54.082125Z","end":"2026-08-12T11:06:54.442695Z","steps":["trace[702628286] 'process raft request' (duration: 150.438288ms)","trace[702628286] 'compare' (duration: 209.718471ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:06:54.443431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:54.082106Z","time spent":"360.743526ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:06:54.442834Z","caller":"traceutil/trace.go:171","msg":"trace[317714424] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"360.483098ms","start":"2026-08-12T11:06:54.082296Z","end":"2026-08-12T11:06:54.442779Z","steps":["trace[317714424] 'process raft request' (duration: 360.333713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:54.442943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.950925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-08-12T11:06:54.443662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.921558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:06:54.443718Z","caller":"traceutil/trace.go:171","msg":"trace[822226165] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2156; }","duration":"344.731538ms","start":"2026-08-12T11:06:54.098947Z","end":"2026-08-12T11:06:54.443679Z","steps":["trace[822226165] 'agreement among raft nodes before linearized reading' (duration: 343.828252ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:54.443753Z","caller":"traceutil/trace.go:171","msg":"trace[68478307] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2156; }","duration":"153.743171ms","start":"2026-08-12T11:06:54.289976Z","end":"2026-08-12T11:06:54.443719Z","steps":["trace[68478307] 'agreement among raft nodes before linearized reading' (duration: 152.926337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:06:54.443786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:54.082254Z","time spent":"361.353533ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:06:54.443829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:06:54.098926Z","time spent":"344.866771ms","remote":"127.0.0.1:45842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-12T11:06:54.444217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.55355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-12T11:06:54.444412Z","caller":"traceutil/trace.go:171","msg":"trace[751476751] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2156; }","duration":"243.753055ms","start":"2026-08-12T11:06:54.200605Z","end":"2026-08-12T11:06:54.444358Z","steps":["trace[751476751] 'agreement among raft nodes before linearized reading' (duration: 242.197461ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:08:15.826785Z","caller":"traceutil/trace.go:171","msg":"trace[108481336] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"110.876241ms","start":"2026-08-12T11:08:15.715877Z","end":"2026-08-12T11:08:15.826753Z","steps":["trace[108481336] 'process raft request' (duration: 110.667744ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:08:15.840073Z","caller":"traceutil/trace.go:171","msg":"trace[419152039] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"101.957431ms","start":"2026-08-12T11:08:15.73809Z","end":"2026-08-12T11:08:15.840048Z","steps":["trace[419152039] 'process raft request' (duration: 101.716775ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:10:50.723679Z","caller":"traceutil/trace.go:171","msg":"trace[2034846503] transaction","detail":"{read_only:false; response_revision:3808; number_of_response:1; }","duration":"122.999229ms","start":"2026-08-12T11:10:50.600647Z","end":"2026-08-12T11:10:50.723647Z","steps":["trace[2034846503] 'process raft request' (duration: 122.807584ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:12:11.336011Z","caller":"traceutil/trace.go:171","msg":"trace[1720210427] transaction","detail":"{read_only:false; response_revision:4697; number_of_response:1; }","duration":"116.50151ms","start":"2026-08-12T11:12:11.219474Z","end":"2026-08-12T11:12:11.335975Z","steps":["trace[1720210427] 'process raft request' (duration: 116.208512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:12:20.281808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.033046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:12:20.282043Z","caller":"traceutil/trace.go:171","msg":"trace[1390227148] linearizableReadLoop","detail":"{readStateIndex:5200; appliedIndex:5199; }","duration":"304.834273ms","start":"2026-08-12T11:12:19.977184Z","end":"2026-08-12T11:12:20.282019Z","steps":["trace[1390227148] 'read index received' (duration: 2.400677ms)","trace[1390227148] 'applied index is now lower than readState.Index' (duration: 302.431996ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:12:20.282158Z","caller":"traceutil/trace.go:171","msg":"trace[1323073799] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"306.428018ms","start":"2026-08-12T11:12:19.975716Z","end":"2026-08-12T11:12:20.282144Z","steps":["trace[1323073799] 'process raft request' (duration: 103.95486ms)","trace[1323073799] 'compare' (duration: 201.840861ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:12:20.282222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:12:19.975707Z","time spent":"306.48816ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1401,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T11:12:20.282395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.182682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-12T11:12:20.282494Z","caller":"traceutil/trace.go:171","msg":"trace[315444939] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5009; }","duration":"305.341426ms","start":"2026-08-12T11:12:19.977133Z","end":"2026-08-12T11:12:20.282474Z","steps":["trace[315444939] 'agreement among raft nodes before linearized reading' (duration: 305.10437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:12:20.282535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:12:19.977108Z","time spent":"305.417599ms","remote":"127.0.0.1:45984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-08-12T11:12:20.282626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.75885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T11:12:20.282672Z","caller":"traceutil/trace.go:171","msg":"trace[1434861978] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5009; }","duration":"245.840251ms","start":"2026-08-12T11:12:20.036819Z","end":"2026-08-12T11:12:20.282659Z","steps":["trace[1434861978] 'agreement among raft nodes before linearized reading' (duration: 245.716278ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:12:20.282885Z","caller":"traceutil/trace.go:171","msg":"trace[508306984] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"304.864293ms","start":"2026-08-12T11:12:19.978004Z","end":"2026-08-12T11:12:20.282869Z","steps":["trace[508306984] 'process raft request' (duration: 303.946998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:12:20.282972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:12:19.977992Z","time spent":"304.922626ms","remote":"127.0.0.1:46272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T11:12:22.426599Z","caller":"traceutil/trace.go:171","msg":"trace[2144058383] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"109.485594ms","start":"2026-08-12T11:12:22.317063Z","end":"2026-08-12T11:12:22.426549Z","steps":["trace[2144058383] 'process raft request' (duration: 85.567995ms)","trace[2144058383] 'compare' (duration: 23.501158ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:12:22.426643Z","caller":"traceutil/trace.go:171","msg":"trace[332686283] linearizableReadLoop","detail":"{readStateIndex:5236; appliedIndex:5235; }","duration":"106.045458ms","start":"2026-08-12T11:12:22.320573Z","end":"2026-08-12T11:12:22.426619Z","steps":["trace[332686283] 'read index received' (duration: 81.987265ms)","trace[332686283] 'applied index is now lower than readState.Index' (duration: 24.057063ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:12:22.426919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.328967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-08-12T11:12:22.426953Z","caller":"traceutil/trace.go:171","msg":"trace[196482803] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5044; }","duration":"106.45621ms","start":"2026-08-12T11:12:22.320484Z","end":"2026-08-12T11:12:22.42694Z","steps":["trace[196482803] 'agreement among raft nodes before linearized reading' (duration: 106.282905ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:12:22.427531Z","caller":"traceutil/trace.go:171","msg":"trace[397172363] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"103.536448ms","start":"2026-08-12T11:12:22.323966Z","end":"2026-08-12T11:12:22.427502Z","steps":["trace[397172363] 'process raft request' (duration: 102.53482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:12:34.236702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.873345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:12:34.236857Z","caller":"traceutil/trace.go:171","msg":"trace[340293687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5279; }","duration":"384.084921ms","start":"2026-08-12T11:12:33.852741Z","end":"2026-08-12T11:12:34.236826Z","steps":["trace[340293687] 'range keys from in-memory index tree' (duration: 383.773372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:12:34.23693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:12:33.852721Z","time spent":"384.191784ms","remote":"127.0.0.1:45868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T11:12:34.237043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.379235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:12:34.237211Z","caller":"traceutil/trace.go:171","msg":"trace[817494009] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"381.601711ms","start":"2026-08-12T11:12:33.855582Z","end":"2026-08-12T11:12:34.237184Z","steps":["trace[817494009] 'process raft request' (duration: 168.927368ms)","trace[817494009] 'compare' (duration: 211.999665ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:12:34.237288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:12:33.855549Z","time spent":"381.705674ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:12:41.765708Z","caller":"traceutil/trace.go:171","msg":"trace[600585476] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"198.835885ms","start":"2026-08-12T11:12:41.566827Z","end":"2026-08-12T11:12:41.765663Z","steps":["trace[600585476] 'process raft request' (duration: 117.141928ms)","trace[600585476] 'compare' (duration: 81.41406ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:12:41.780151Z","caller":"traceutil/trace.go:171","msg":"trace[68667607] linearizableReadLoop","detail":"{readStateIndex:5688; appliedIndex:5686; }","duration":"118.232899ms","start":"2026-08-12T11:12:41.661892Z","end":"2026-08-12T11:12:41.780125Z","steps":["trace[68667607] 'read index received' (duration: 22.095948ms)","trace[68667607] 'applied index is now lower than readState.Index' (duration: 96.135661ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:12:41.780254Z","caller":"traceutil/trace.go:171","msg":"trace[1637222835] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"200.832402ms","start":"2026-08-12T11:12:41.579377Z","end":"2026-08-12T11:12:41.78021Z","steps":["trace[1637222835] 'process raft request' (duration: 200.570475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:12:41.780369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.453055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-12T11:12:41.780421Z","caller":"traceutil/trace.go:171","msg":"trace[440168194] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5484; }","duration":"118.567148ms","start":"2026-08-12T11:12:41.661833Z","end":"2026-08-12T11:12:41.7804Z","steps":["trace[440168194] 'agreement among raft nodes before linearized reading' (duration: 118.394924ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:12:41.925328Z","caller":"traceutil/trace.go:171","msg":"trace[857950012] linearizableReadLoop","detail":"{readStateIndex:5691; appliedIndex:5690; }","duration":"130.887804ms","start":"2026-08-12T11:12:41.794402Z","end":"2026-08-12T11:12:41.92529Z","steps":["trace[857950012] 'read index received' (duration: 111.333026ms)","trace[857950012] 'applied index is now lower than readState.Index' (duration: 19.553048ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:12:41.925479Z","caller":"traceutil/trace.go:171","msg":"trace[275245808] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"131.145551ms","start":"2026-08-12T11:12:41.794318Z","end":"2026-08-12T11:12:41.925464Z","steps":["trace[275245808] 'process raft request' (duration: 111.469601ms)","trace[275245808] 'compare' (duration: 19.31735ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:12:41.925836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.401379ms","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-08-12T11:12:41.9259Z","caller":"traceutil/trace.go:171","msg":"trace[318849396] 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:5487; }","duration":"131.477781ms","start":"2026-08-12T11:12:41.794394Z","end":"2026-08-12T11:12:41.925872Z","steps":["trace[318849396] 'agreement among raft nodes before linearized reading' (duration: 131.264875ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:12:41.935519Z","caller":"traceutil/trace.go:171","msg":"trace[305824519] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"135.535293ms","start":"2026-08-12T11:12:41.799925Z","end":"2026-08-12T11:12:41.93546Z","steps":["trace[305824519] 'process raft request' (duration: 135.178314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:12:41.93556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.862131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54774ff1-ab97-4eb3-8bd2-d0ff77178d9b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-12T11:12:41.936329Z","caller":"traceutil/trace.go:171","msg":"trace[1648357639] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54774ff1-ab97-4eb3-8bd2-d0ff77178d9b; range_end:; response_count:1; response_revision:5488; }","duration":"137.678945ms","start":"2026-08-12T11:12:41.798625Z","end":"2026-08-12T11:12:41.936304Z","steps":["trace[1648357639] 'agreement among raft nodes before linearized reading' (duration: 136.723137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:12:42.254147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.231521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:12:42.254357Z","caller":"traceutil/trace.go:171","msg":"trace[2079545550] linearizableReadLoop","detail":"{readStateIndex:5693; appliedIndex:5692; }","duration":"263.68292ms","start":"2026-08-12T11:12:41.990651Z","end":"2026-08-12T11:12:42.254334Z","steps":["trace[2079545550] 'read index received' (duration: 86.159312ms)","trace[2079545550] 'applied index is now lower than readState.Index' (duration: 177.521788ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:12:42.254441Z","caller":"traceutil/trace.go:171","msg":"trace[96775192] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"307.725794ms","start":"2026-08-12T11:12:41.946673Z","end":"2026-08-12T11:12:42.254399Z","steps":["trace[96775192] 'process raft request' (duration: 130.119093ms)","trace[96775192] 'compare' (duration: 177.013425ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:12:42.254483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.817625ms","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-08-12T11:12:42.254659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:12:41.946649Z","time spent":"307.874818ms","remote":"127.0.0.1:46012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:12:42.254706Z","caller":"traceutil/trace.go:171","msg":"trace[2068829576] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5489; }","duration":"263.943688ms","start":"2026-08-12T11:12:41.990587Z","end":"2026-08-12T11:12:42.254531Z","steps":["trace[2068829576] 'agreement among raft nodes before linearized reading' (duration: 263.811454ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:46.940695Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2960} {"level":"info","ts":"2026-08-12T11:13:47.012421Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2960,"took":"69.455945ms","hash":2362548071,"current-db-size-bytes":58118144,"current-db-size":"58 MB","current-db-size-in-use-bytes":34021376,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-12T11:13:47.012504Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2362548071,"revision":2960,"compact-revision":-1} {"level":"info","ts":"2026-08-12T11:18:46.950795Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6040} {"level":"info","ts":"2026-08-12T11:18:47.04795Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6040,"took":"93.10416ms","hash":3639768188,"current-db-size-bytes":58118144,"current-db-size":"58 MB","current-db-size-in-use-bytes":21774336,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-12T11:18:47.048021Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3639768188,"revision":6040,"compact-revision":2960} {"level":"info","ts":"2026-08-12T11:18:53.191617Z","caller":"traceutil/trace.go:171","msg":"trace[336869947] transaction","detail":"{read_only:false; response_revision:8299; number_of_response:1; }","duration":"191.54326ms","start":"2026-08-12T11:18:53.00005Z","end":"2026-08-12T11:18:53.191593Z","steps":["trace[336869947] 'process raft request' (duration: 191.388236ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:18:53.352355Z","caller":"traceutil/trace.go:171","msg":"trace[1521156768] linearizableReadLoop","detail":"{readStateIndex:8594; appliedIndex:8593; }","duration":"308.672455ms","start":"2026-08-12T11:18:53.043651Z","end":"2026-08-12T11:18:53.352324Z","steps":["trace[1521156768] 'read index received' (duration: 147.964589ms)","trace[1521156768] 'applied index is now lower than readState.Index' (duration: 160.706286ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:18:53.352495Z","caller":"traceutil/trace.go:171","msg":"trace[1210404817] transaction","detail":"{read_only:false; response_revision:8300; number_of_response:1; }","duration":"317.233525ms","start":"2026-08-12T11:18:53.035192Z","end":"2026-08-12T11:18:53.352425Z","steps":["trace[1210404817] 'process raft request' (duration: 316.956227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:18:53.352693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:18:53.035178Z","time spent":"317.405451ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:18:53.352707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.033105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-12T11:18:53.35277Z","caller":"traceutil/trace.go:171","msg":"trace[1416425811] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8300; }","duration":"309.165ms","start":"2026-08-12T11:18:53.043584Z","end":"2026-08-12T11:18:53.352749Z","steps":["trace[1416425811] 'agreement among raft nodes before linearized reading' (duration: 308.954504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:18:53.353457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:18:53.043555Z","time spent":"309.88091ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-12T11:19:04.719397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.96433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:19:04.719491Z","caller":"traceutil/trace.go:171","msg":"trace[656340850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8356; }","duration":"117.144414ms","start":"2026-08-12T11:19:04.602326Z","end":"2026-08-12T11:19:04.71947Z","steps":["trace[656340850] 'range keys from in-memory index tree' (duration: 116.827676ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:19:04.992802Z","caller":"traceutil/trace.go:171","msg":"trace[1410871816] linearizableReadLoop","detail":"{readStateIndex:8653; appliedIndex:8652; }","duration":"363.011859ms","start":"2026-08-12T11:19:04.629755Z","end":"2026-08-12T11:19:04.992767Z","steps":["trace[1410871816] 'read index received' (duration: 362.892925ms)","trace[1410871816] 'applied index is now lower than readState.Index' (duration: 117.504µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:19:04.992924Z","caller":"traceutil/trace.go:171","msg":"trace[434073306] transaction","detail":"{read_only:false; response_revision:8357; number_of_response:1; }","duration":"389.388958ms","start":"2026-08-12T11:19:04.603484Z","end":"2026-08-12T11:19:04.992873Z","steps":["trace[434073306] 'process raft request' (duration: 389.038228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:19:04.993154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:19:04.603452Z","time spent":"389.574983ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:19:04.993153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.372629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-12T11:19:04.99326Z","caller":"traceutil/trace.go:171","msg":"trace[1529184281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8357; }","duration":"363.549264ms","start":"2026-08-12T11:19:04.629689Z","end":"2026-08-12T11:19:04.993238Z","steps":["trace[1529184281] 'agreement among raft nodes before linearized reading' (duration: 363.234645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:19:04.993311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:19:04.629671Z","time spent":"363.630776ms","remote":"127.0.0.1:46086","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":"info","ts":"2026-08-12T11:19:05.002735Z","caller":"traceutil/trace.go:171","msg":"trace[1156005928] transaction","detail":"{read_only:false; response_revision:8358; number_of_response:1; }","duration":"261.538003ms","start":"2026-08-12T11:19:04.741174Z","end":"2026-08-12T11:19:05.002712Z","steps":["trace[1156005928] 'process raft request' (duration: 261.269996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:19:05.002751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.413123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:19:05.002851Z","caller":"traceutil/trace.go:171","msg":"trace[1284399876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8358; }","duration":"147.604168ms","start":"2026-08-12T11:19:04.855215Z","end":"2026-08-12T11:19:05.002819Z","steps":["trace[1284399876] 'agreement among raft nodes before linearized reading' (duration: 147.428963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:19:05.002926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.010295ms","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-08-12T11:19:05.003009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.381931ms","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-08-12T11:19:05.003052Z","caller":"traceutil/trace.go:171","msg":"trace[2012051195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8358; }","duration":"268.334213ms","start":"2026-08-12T11:19:04.734696Z","end":"2026-08-12T11:19:05.003031Z","steps":["trace[2012051195] 'agreement among raft nodes before linearized reading' (duration: 267.931462ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:19:05.003122Z","caller":"traceutil/trace.go:171","msg":"trace[2001513339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8358; }","duration":"265.568256ms","start":"2026-08-12T11:19:04.737525Z","end":"2026-08-12T11:19:05.003093Z","steps":["trace[2001513339] 'agreement among raft nodes before linearized reading' (duration: 265.204646ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:19:05.676381Z","caller":"traceutil/trace.go:171","msg":"trace[326694083] linearizableReadLoop","detail":"{readStateIndex:8659; appliedIndex:8658; }","duration":"252.243713ms","start":"2026-08-12T11:19:05.424103Z","end":"2026-08-12T11:19:05.676347Z","steps":["trace[326694083] 'read index received' (duration: 252.033787ms)","trace[326694083] 'applied index is now lower than readState.Index' (duration: 208.096µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:19:05.67651Z","caller":"traceutil/trace.go:171","msg":"trace[314009576] transaction","detail":"{read_only:false; response_revision:8362; number_of_response:1; }","duration":"279.040694ms","start":"2026-08-12T11:19:05.397428Z","end":"2026-08-12T11:19:05.676468Z","steps":["trace[314009576] 'process raft request' (duration: 278.705795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:19:05.676802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.392191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-12T11:19:05.67687Z","caller":"traceutil/trace.go:171","msg":"trace[1610194944] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8362; }","duration":"250.502815ms","start":"2026-08-12T11:19:05.426352Z","end":"2026-08-12T11:19:05.676855Z","steps":["trace[1610194944] 'agreement among raft nodes before linearized reading' (duration: 250.266078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:19:05.676857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.701185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-12T11:19:05.676899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.262716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wfcf6\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-12T11:19:05.676983Z","caller":"traceutil/trace.go:171","msg":"trace[1707010412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8362; }","duration":"252.90409ms","start":"2026-08-12T11:19:05.424052Z","end":"2026-08-12T11:19:05.676956Z","steps":["trace[1707010412] 'agreement among raft nodes before linearized reading' (duration: 252.497469ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:19:05.676997Z","caller":"traceutil/trace.go:171","msg":"trace[1065665331] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wfcf6; range_end:; response_count:1; response_revision:8362; }","duration":"113.40175ms","start":"2026-08-12T11:19:05.563567Z","end":"2026-08-12T11:19:05.676969Z","steps":["trace[1065665331] 'agreement among raft nodes before linearized reading' (duration: 113.088571ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:19:34.587913Z","caller":"traceutil/trace.go:171","msg":"trace[563354791] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"321.160296ms","start":"2026-08-12T11:19:34.266713Z","end":"2026-08-12T11:19:34.587873Z","steps":["trace[563354791] 'process raft request' (duration: 320.921939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:19:34.58817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:19:34.26668Z","time spent":"321.352661ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:19:37.900841Z","caller":"traceutil/trace.go:171","msg":"trace[261232040] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"107.805384ms","start":"2026-08-12T11:19:37.792992Z","end":"2026-08-12T11:19:37.900797Z","steps":["trace[261232040] 'process raft request' (duration: 107.540646ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:20:05.66348Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d2.wal"} {"level":"info","ts":"2026-08-12T11:21:37.131726Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2ba8e3c2b0588654","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T11:21:37.138013Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-12T11:21:37.138254Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-12T11:23:46.963484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8130} {"level":"info","ts":"2026-08-12T11:23:47.029938Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8130,"took":"63.742986ms","hash":3432475278,"current-db-size-bytes":58118144,"current-db-size":"58 MB","current-db-size-in-use-bytes":29724672,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-12T11:23:47.030035Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3432475278,"revision":8130,"compact-revision":6040} {"level":"info","ts":"2026-08-12T11:24:50.529333Z","caller":"traceutil/trace.go:171","msg":"trace[71603348] linearizableReadLoop","detail":"{readStateIndex:11680; appliedIndex:11679; }","duration":"142.749873ms","start":"2026-08-12T11:24:50.38655Z","end":"2026-08-12T11:24:50.5293Z","steps":["trace[71603348] 'read index received' (duration: 142.586488ms)","trace[71603348] 'applied index is now lower than readState.Index' (duration: 161.995µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:24:50.529413Z","caller":"traceutil/trace.go:171","msg":"trace[96885350] transaction","detail":"{read_only:false; response_revision:11296; number_of_response:1; }","duration":"161.195489ms","start":"2026-08-12T11:24:50.36819Z","end":"2026-08-12T11:24:50.529385Z","steps":["trace[96885350] 'process raft request' (duration: 160.914681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:24:50.529635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.049962ms","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-08-12T11:24:50.529681Z","caller":"traceutil/trace.go:171","msg":"trace[1567631434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11296; }","duration":"143.179275ms","start":"2026-08-12T11:24:50.38649Z","end":"2026-08-12T11:24:50.529669Z","steps":["trace[1567631434] 'agreement among raft nodes before linearized reading' (duration: 142.937047ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:28:46.971257Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10732} {"level":"info","ts":"2026-08-12T11:28:47.058129Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10732,"took":"83.920566ms","hash":1779546789,"current-db-size-bytes":58118144,"current-db-size":"58 MB","current-db-size-in-use-bytes":27049984,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-12T11:28:47.058207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1779546789,"revision":10732,"compact-revision":8130} {"level":"info","ts":"2026-08-12T11:31:07.226183Z","caller":"traceutil/trace.go:171","msg":"trace[38915032] transaction","detail":"{read_only:false; response_revision:13829; number_of_response:1; }","duration":"137.428242ms","start":"2026-08-12T11:31:07.088734Z","end":"2026-08-12T11:31:07.226162Z","steps":["trace[38915032] 'process raft request' (duration: 137.297599ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:31:07.243708Z","caller":"traceutil/trace.go:171","msg":"trace[1715353458] linearizableReadLoop","detail":"{readStateIndex:14302; appliedIndex:14300; }","duration":"109.041522ms","start":"2026-08-12T11:31:07.134641Z","end":"2026-08-12T11:31:07.243682Z","steps":["trace[1715353458] 'read index received' (duration: 91.436473ms)","trace[1715353458] 'applied index is now lower than readState.Index' (duration: 17.603609ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:31:07.243912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.238748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:31:07.243959Z","caller":"traceutil/trace.go:171","msg":"trace[1398878814] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:13830; }","duration":"109.338951ms","start":"2026-08-12T11:31:07.134606Z","end":"2026-08-12T11:31:07.243945Z","steps":["trace[1398878814] 'agreement among raft nodes before linearized reading' (duration: 109.208447ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:31:07.244327Z","caller":"traceutil/trace.go:171","msg":"trace[1662174481] transaction","detail":"{read_only:false; response_revision:13830; number_of_response:1; }","duration":"154.66956ms","start":"2026-08-12T11:31:07.089643Z","end":"2026-08-12T11:31:07.244313Z","steps":["trace[1662174481] 'process raft request' (duration: 153.851597ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:31:47.174192Z","caller":"traceutil/trace.go:171","msg":"trace[214562479] transaction","detail":"{read_only:false; response_revision:14047; number_of_response:1; }","duration":"311.445332ms","start":"2026-08-12T11:31:46.862712Z","end":"2026-08-12T11:31:47.174157Z","steps":["trace[214562479] 'process raft request' (duration: 311.246976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:31:47.174404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:31:46.862686Z","time spent":"311.621547ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:31:47.175016Z","caller":"traceutil/trace.go:171","msg":"trace[1266859072] transaction","detail":"{read_only:false; response_revision:14048; number_of_response:1; }","duration":"129.636867ms","start":"2026-08-12T11:31:47.04535Z","end":"2026-08-12T11:31:47.174987Z","steps":["trace[1266859072] 'process raft request' (duration: 129.478223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:32:10.473299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.22571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wfcf6\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-12T11:32:10.473373Z","caller":"traceutil/trace.go:171","msg":"trace[1627554114] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wfcf6; range_end:; response_count:1; response_revision:14363; }","duration":"311.370675ms","start":"2026-08-12T11:32:10.161985Z","end":"2026-08-12T11:32:10.473355Z","steps":["trace[1627554114] 'range keys from in-memory index tree' (duration: 311.028445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:32:10.473429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:32:10.161949Z","time spent":"311.473408ms","remote":"127.0.0.1:46012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-wfcf6\" "} {"level":"warn","ts":"2026-08-12T11:32:10.473468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.721487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-12T11:32:10.473501Z","caller":"traceutil/trace.go:171","msg":"trace[63814824] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14363; }","duration":"246.798459ms","start":"2026-08-12T11:32:10.226694Z","end":"2026-08-12T11:32:10.473493Z","steps":["trace[63814824] 'range keys from in-memory index tree' (duration: 246.620094ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:32:10.4736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.512383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-12T11:32:10.473679Z","caller":"traceutil/trace.go:171","msg":"trace[1524290013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14363; }","duration":"266.954227ms","start":"2026-08-12T11:32:10.206718Z","end":"2026-08-12T11:32:10.473673Z","steps":["trace[1524290013] 'range keys from in-memory index tree' (duration: 266.370509ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:33:05.311034Z","caller":"traceutil/trace.go:171","msg":"trace[105930454] linearizableReadLoop","detail":"{readStateIndex:15520; appliedIndex:15519; }","duration":"144.589177ms","start":"2026-08-12T11:33:05.166414Z","end":"2026-08-12T11:33:05.311003Z","steps":["trace[105930454] 'read index received' (duration: 72.565791ms)","trace[105930454] 'applied index is now lower than readState.Index' (duration: 72.021485ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:33:05.311429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.002067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-12T11:33:05.31154Z","caller":"traceutil/trace.go:171","msg":"trace[1806190248] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15008; }","duration":"145.176363ms","start":"2026-08-12T11:33:05.166341Z","end":"2026-08-12T11:33:05.311517Z","steps":["trace[1806190248] 'agreement among raft nodes before linearized reading' (duration: 144.892875ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:33:42.237503Z","caller":"traceutil/trace.go:171","msg":"trace[1181084442] linearizableReadLoop","detail":"{readStateIndex:15725; appliedIndex:15723; }","duration":"115.034985ms","start":"2026-08-12T11:33:42.122436Z","end":"2026-08-12T11:33:42.237471Z","steps":["trace[1181084442] 'read index received' (duration: 79.249208ms)","trace[1181084442] 'applied index is now lower than readState.Index' (duration: 35.784347ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:33:42.237541Z","caller":"traceutil/trace.go:171","msg":"trace[733964532] transaction","detail":"{read_only:false; response_revision:15205; number_of_response:1; }","duration":"155.577414ms","start":"2026-08-12T11:33:42.081925Z","end":"2026-08-12T11:33:42.237502Z","steps":["trace[733964532] 'process raft request' (duration: 119.740396ms)","trace[733964532] 'compare' (duration: 35.5814ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:33:42.2376Z","caller":"traceutil/trace.go:171","msg":"trace[352921529] transaction","detail":"{read_only:false; response_revision:15206; number_of_response:1; }","duration":"153.145186ms","start":"2026-08-12T11:33:42.084411Z","end":"2026-08-12T11:33:42.237557Z","steps":["trace[352921529] 'process raft request' (duration: 153.008642ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:33:42.237893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.428095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:33:42.237953Z","caller":"traceutil/trace.go:171","msg":"trace[519750575] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15206; }","duration":"115.644952ms","start":"2026-08-12T11:33:42.122292Z","end":"2026-08-12T11:33:42.237937Z","steps":["trace[519750575] 'agreement among raft nodes before linearized reading' (duration: 115.359494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:33:42.237972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.539472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:33:42.238066Z","caller":"traceutil/trace.go:171","msg":"trace[206065953] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15206; }","duration":"103.673905ms","start":"2026-08-12T11:33:42.134371Z","end":"2026-08-12T11:33:42.238045Z","steps":["trace[206065953] 'agreement among raft nodes before linearized reading' (duration: 103.49049ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:33:42.237985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.49557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-08-12T11:33:42.238081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.476516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:680"} {"level":"info","ts":"2026-08-12T11:33:42.238129Z","caller":"traceutil/trace.go:171","msg":"trace[1474208846] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15206; }","duration":"115.59682ms","start":"2026-08-12T11:33:42.122513Z","end":"2026-08-12T11:33:42.23811Z","steps":["trace[1474208846] 'agreement among raft nodes before linearized reading' (duration: 115.304282ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:33:42.238189Z","caller":"traceutil/trace.go:171","msg":"trace[230788414] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15206; }","duration":"103.752457ms","start":"2026-08-12T11:33:42.134356Z","end":"2026-08-12T11:33:42.238109Z","steps":["trace[230788414] 'agreement among raft nodes before linearized reading' (duration: 103.47075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:33:43.137931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.545689ms","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-08-12T11:33:43.138702Z","caller":"traceutil/trace.go:171","msg":"trace[1795567564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15207; }","duration":"142.361613ms","start":"2026-08-12T11:33:42.996299Z","end":"2026-08-12T11:33:43.13868Z","steps":["trace[1795567564] 'range keys from in-memory index tree' (duration: 141.292162ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:33:46.97788Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12827} {"level":"info","ts":"2026-08-12T11:33:47.060255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12827,"took":"79.069594ms","hash":1602224212,"current-db-size-bytes":58118144,"current-db-size":"58 MB","current-db-size-in-use-bytes":29696000,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-12T11:33:47.060336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1602224212,"revision":12827,"compact-revision":10732} {"level":"info","ts":"2026-08-12T11:38:46.985018Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15228} {"level":"info","ts":"2026-08-12T11:38:47.085631Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15228,"took":"96.81415ms","hash":3840358785,"current-db-size-bytes":58118144,"current-db-size":"58 MB","current-db-size-in-use-bytes":31301632,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T11:38:47.085707Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3840358785,"revision":15228,"compact-revision":12827} {"level":"info","ts":"2026-08-12T11:39:39.24955Z","caller":"traceutil/trace.go:171","msg":"trace[1698220302] transaction","detail":"{read_only:false; response_revision:17831; number_of_response:1; }","duration":"180.216117ms","start":"2026-08-12T11:39:39.069307Z","end":"2026-08-12T11:39:39.249523Z","steps":["trace[1698220302] 'process raft request' (duration: 179.780685ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:39:39.493322Z","caller":"traceutil/trace.go:171","msg":"trace[108357802] transaction","detail":"{read_only:false; response_revision:17832; number_of_response:1; }","duration":"238.637535ms","start":"2026-08-12T11:39:39.254655Z","end":"2026-08-12T11:39:39.493292Z","steps":["trace[108357802] 'process raft request' (duration: 238.44869ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:39:39.514671Z","caller":"traceutil/trace.go:171","msg":"trace[847315458] linearizableReadLoop","detail":"{readStateIndex:18441; appliedIndex:18438; }","duration":"258.348772ms","start":"2026-08-12T11:39:39.256295Z","end":"2026-08-12T11:39:39.514644Z","steps":["trace[847315458] 'read index received' (duration: 236.918447ms)","trace[847315458] 'applied index is now lower than readState.Index' (duration: 21.429095ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:39:39.514715Z","caller":"traceutil/trace.go:171","msg":"trace[1004224645] transaction","detail":"{read_only:false; response_revision:17833; number_of_response:1; }","duration":"259.797133ms","start":"2026-08-12T11:39:39.254882Z","end":"2026-08-12T11:39:39.514679Z","steps":["trace[1004224645] 'process raft request' (duration: 259.550487ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:39:39.514783Z","caller":"traceutil/trace.go:171","msg":"trace[1052082042] transaction","detail":"{read_only:false; response_revision:17834; number_of_response:1; }","duration":"258.889168ms","start":"2026-08-12T11:39:39.255848Z","end":"2026-08-12T11:39:39.514737Z","steps":["trace[1052082042] 'process raft request' (duration: 258.742344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:39:39.516007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.69187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:946785"} {"level":"info","ts":"2026-08-12T11:39:39.516065Z","caller":"traceutil/trace.go:171","msg":"trace[683377256] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17834; }","duration":"259.777912ms","start":"2026-08-12T11:39:39.256275Z","end":"2026-08-12T11:39:39.516053Z","steps":["trace[683377256] 'agreement among raft nodes before linearized reading' (duration: 258.531087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:39:39.533982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.185138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:39:39.534062Z","caller":"traceutil/trace.go:171","msg":"trace[1266124228] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:17835; }","duration":"235.309042ms","start":"2026-08-12T11:39:39.298733Z","end":"2026-08-12T11:39:39.534042Z","steps":["trace[1266124228] 'agreement among raft nodes before linearized reading' (duration: 235.145218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:39:39.534318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.749157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:39:39.534356Z","caller":"traceutil/trace.go:171","msg":"trace[372823675] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:17835; }","duration":"138.8468ms","start":"2026-08-12T11:39:39.3955Z","end":"2026-08-12T11:39:39.534347Z","steps":["trace[372823675] 'agreement among raft nodes before linearized reading' (duration: 138.785378ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:42:40.055824Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2ba8e3c2b0588654","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T11:42:40.062101Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-12T11:42:40.062212Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-12T11:43:46.992385Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17454} {"level":"info","ts":"2026-08-12T11:43:47.102109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17454,"took":"105.041156ms","hash":2090802890,"current-db-size-bytes":58118144,"current-db-size":"58 MB","current-db-size-in-use-bytes":34029568,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-12T11:43:47.102207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2090802890,"revision":17454,"compact-revision":15228} {"level":"info","ts":"2026-08-12T11:45:03.383198Z","caller":"traceutil/trace.go:171","msg":"trace[1781982549] transaction","detail":"{read_only:false; response_revision:20384; number_of_response:1; }","duration":"104.320566ms","start":"2026-08-12T11:45:03.278821Z","end":"2026-08-12T11:45:03.383141Z","steps":["trace[1781982549] 'process raft request' (duration: 103.993997ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:45:07.548113Z","caller":"traceutil/trace.go:171","msg":"trace[840929243] transaction","detail":"{read_only:false; response_revision:20409; number_of_response:1; }","duration":"145.215682ms","start":"2026-08-12T11:45:07.402849Z","end":"2026-08-12T11:45:07.548065Z","steps":["trace[840929243] 'process raft request' (duration: 144.976834ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:45:25.052852Z","caller":"traceutil/trace.go:171","msg":"trace[865657478] transaction","detail":"{read_only:false; response_revision:20510; number_of_response:1; }","duration":"283.905268ms","start":"2026-08-12T11:45:24.768902Z","end":"2026-08-12T11:45:25.052807Z","steps":["trace[865657478] 'process raft request' (duration: 283.673272ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:45:25.136784Z","caller":"traceutil/trace.go:171","msg":"trace[2110313551] transaction","detail":"{read_only:false; response_revision:20511; number_of_response:1; }","duration":"347.228788ms","start":"2026-08-12T11:45:24.7895Z","end":"2026-08-12T11:45:25.136729Z","steps":["trace[2110313551] 'process raft request' (duration: 331.39706ms)","trace[2110313551] 'compare' (duration: 15.56629ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:45:25.137004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:45:24.789486Z","time spent":"347.399772ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:45:25.136766Z","caller":"traceutil/trace.go:171","msg":"trace[2072658376] transaction","detail":"{read_only:false; response_revision:20512; number_of_response:1; }","duration":"331.907755ms","start":"2026-08-12T11:45:24.804824Z","end":"2026-08-12T11:45:25.136732Z","steps":["trace[2072658376] 'process raft request' (duration: 331.806402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:45:25.137202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:45:24.804811Z","time spent":"332.268315ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:45:25.2818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.070898ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-12T11:48:46.999723Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19855} {"level":"info","ts":"2026-08-12T11:48:47.110004Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19855,"took":"105.659364ms","hash":1956623760,"current-db-size-bytes":58118144,"current-db-size":"58 MB","current-db-size-in-use-bytes":30470144,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-12T11:48:47.110071Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1956623760,"revision":19855,"compact-revision":17454} {"level":"info","ts":"2026-08-12T11:49:11.049496Z","caller":"traceutil/trace.go:171","msg":"trace[310563712] linearizableReadLoop","detail":"{readStateIndex:23640; appliedIndex:23639; }","duration":"101.926179ms","start":"2026-08-12T11:49:10.94755Z","end":"2026-08-12T11:49:11.049476Z","steps":["trace[310563712] 'read index received' (duration: 101.809716ms)","trace[310563712] 'applied index is now lower than readState.Index' (duration: 115.723µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:49:11.04954Z","caller":"traceutil/trace.go:171","msg":"trace[1457707419] transaction","detail":"{read_only:false; response_revision:22874; number_of_response:1; }","duration":"192.975841ms","start":"2026-08-12T11:49:10.856538Z","end":"2026-08-12T11:49:11.049514Z","steps":["trace[1457707419] 'process raft request' (duration: 192.785466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:49:11.049744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.180096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-12T11:49:11.049782Z","caller":"traceutil/trace.go:171","msg":"trace[1277475700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22874; }","duration":"102.273918ms","start":"2026-08-12T11:49:10.947496Z","end":"2026-08-12T11:49:11.04977Z","steps":["trace[1277475700] 'agreement among raft nodes before linearized reading' (duration: 102.107384ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:50:41.19567Z","caller":"traceutil/trace.go:171","msg":"trace[389831431] transaction","detail":"{read_only:false; response_revision:23874; number_of_response:1; }","duration":"113.853594ms","start":"2026-08-12T11:50:41.081764Z","end":"2026-08-12T11:50:41.195617Z","steps":["trace[389831431] 'process raft request' (duration: 113.370161ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:44.676819Z","caller":"traceutil/trace.go:171","msg":"trace[686023527] linearizableReadLoop","detail":"{readStateIndex:25861; appliedIndex:25860; }","duration":"271.524673ms","start":"2026-08-12T11:52:44.405273Z","end":"2026-08-12T11:52:44.676798Z","steps":["trace[686023527] 'read index received' (duration: 271.485162ms)","trace[686023527] 'applied index is now lower than readState.Index' (duration: 38.471µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:44.676898Z","caller":"traceutil/trace.go:171","msg":"trace[405650871] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"350.078757ms","start":"2026-08-12T11:52:44.326771Z","end":"2026-08-12T11:52:44.67685Z","steps":["trace[405650871] 'process raft request' (duration: 349.859291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:44.677021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.742328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-12T11:52:44.677058Z","caller":"traceutil/trace.go:171","msg":"trace[57582357] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25043; }","duration":"271.827541ms","start":"2026-08-12T11:52:44.405219Z","end":"2026-08-12T11:52:44.677047Z","steps":["trace[57582357] 'agreement among raft nodes before linearized reading' (duration: 271.689167ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:44.676994Z","caller":"traceutil/trace.go:171","msg":"trace[274214482] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"347.449913ms","start":"2026-08-12T11:52:44.329451Z","end":"2026-08-12T11:52:44.676901Z","steps":["trace[274214482] 'process raft request' (duration: 347.311279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:44.677159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:44.326751Z","time spent":"350.283393ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:52:44.677264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:44.329423Z","time spent":"347.70309ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:52:44.69677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.004143ms","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:8"} {"level":"info","ts":"2026-08-12T11:52:44.696882Z","caller":"traceutil/trace.go:171","msg":"trace[556076449] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25044; }","duration":"106.173008ms","start":"2026-08-12T11:52:44.590686Z","end":"2026-08-12T11:52:44.696859Z","steps":["trace[556076449] 'agreement among raft nodes before linearized reading' (duration: 105.948222ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:44.945854Z","caller":"traceutil/trace.go:171","msg":"trace[609900078] linearizableReadLoop","detail":"{readStateIndex:25864; appliedIndex:25863; }","duration":"228.3804ms","start":"2026-08-12T11:52:44.717444Z","end":"2026-08-12T11:52:44.945825Z","steps":["trace[609900078] 'read index received' (duration: 149.343291ms)","trace[609900078] 'applied index is now lower than readState.Index' (duration: 79.035629ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:44.945985Z","caller":"traceutil/trace.go:171","msg":"trace[557226793] transaction","detail":"{read_only:false; response_revision:25046; number_of_response:1; }","duration":"228.74372ms","start":"2026-08-12T11:52:44.717212Z","end":"2026-08-12T11:52:44.945955Z","steps":["trace[557226793] 'process raft request' (duration: 149.539147ms)","trace[557226793] 'compare' (duration: 78.851194ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:52:44.946138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.682229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-12T11:52:44.946502Z","caller":"traceutil/trace.go:171","msg":"trace[82309451] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25046; }","duration":"229.09949ms","start":"2026-08-12T11:52:44.71738Z","end":"2026-08-12T11:52:44.94648Z","steps":["trace[82309451] 'agreement among raft nodes before linearized reading' (duration: 228.581276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:45.372141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.085221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:52:45.372374Z","caller":"traceutil/trace.go:171","msg":"trace[1434785230] transaction","detail":"{read_only:false; response_revision:25048; number_of_response:1; }","duration":"544.121225ms","start":"2026-08-12T11:52:44.828233Z","end":"2026-08-12T11:52:45.372355Z","steps":["trace[1434785230] 'process raft request' (duration: 544.016482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:45.372478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:44.828218Z","time spent":"544.201037ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:52:45.372507Z","caller":"traceutil/trace.go:171","msg":"trace[237775577] transaction","detail":"{read_only:false; response_revision:25047; number_of_response:1; }","duration":"626.028974ms","start":"2026-08-12T11:52:44.74644Z","end":"2026-08-12T11:52:45.372469Z","steps":["trace[237775577] 'process raft request' (duration: 325.519161ms)","trace[237775577] 'compare' (duration: 299.952297ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:45.372637Z","caller":"traceutil/trace.go:171","msg":"trace[2072972529] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"500.536889ms","start":"2026-08-12T11:52:44.872051Z","end":"2026-08-12T11:52:45.372587Z","steps":["trace[2072972529] 'process raft request' (duration: 500.260882ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:45.372685Z","caller":"traceutil/trace.go:171","msg":"trace[2042805066] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"422.170309ms","start":"2026-08-12T11:52:44.950488Z","end":"2026-08-12T11:52:45.372659Z","steps":["trace[2042805066] 'process raft request' (duration: 422.040756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:45.372747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:44.746422Z","time spent":"626.192819ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:52:45.372838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:44.872026Z","time spent":"500.709333ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:52:45.372945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:44.950451Z","time spent":"422.373935ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:52:45.373109Z","caller":"traceutil/trace.go:171","msg":"trace[1265348403] transaction","detail":"{read_only:false; response_revision:25050; number_of_response:1; }","duration":"423.141126ms","start":"2026-08-12T11:52:44.949956Z","end":"2026-08-12T11:52:45.373097Z","steps":["trace[1265348403] 'process raft request' (duration: 422.509019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:45.374229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:44.949929Z","time spent":"424.250869ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:52:45.37316Z","caller":"traceutil/trace.go:171","msg":"trace[485079682] linearizableReadLoop","detail":"{readStateIndex:25867; appliedIndex:25864; }","duration":"427.215592ms","start":"2026-08-12T11:52:44.94593Z","end":"2026-08-12T11:52:45.373145Z","steps":["trace[485079682] 'read index received' (duration: 126.100032ms)","trace[485079682] 'applied index is now lower than readState.Index' (duration: 301.1142ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:52:45.529097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.497123ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-08-12T11:52:45.529146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.461026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:52:45.5292Z","caller":"traceutil/trace.go:171","msg":"trace[49189754] linearizableReadLoop","detail":"{readStateIndex:25870; appliedIndex:25869; }","duration":"154.366143ms","start":"2026-08-12T11:52:45.374814Z","end":"2026-08-12T11:52:45.52918Z","steps":["trace[49189754] 'read index received' (duration: 54.821µs)","trace[49189754] 'applied index is now lower than readState.Index' (duration: 154.309982ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:45.529236Z","caller":"traceutil/trace.go:171","msg":"trace[1991812440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25051; }","duration":"675.618719ms","start":"2026-08-12T11:52:44.853592Z","end":"2026-08-12T11:52:45.529211Z","steps":["trace[1991812440] 'agreement among raft nodes before linearized reading' (duration: 519.595439ms)","trace[1991812440] 'range keys from in-memory index tree' (duration: 155.883877ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:52:45.529291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.783983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T11:52:45.529291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:44.853564Z","time spent":"675.717672ms","remote":"127.0.0.1:45884","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-08-12T11:52:45.52932Z","caller":"traceutil/trace.go:171","msg":"trace[193814489] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:25051; }","duration":"329.869265ms","start":"2026-08-12T11:52:45.19944Z","end":"2026-08-12T11:52:45.529309Z","steps":["trace[193814489] 'agreement among raft nodes before linearized reading' (duration: 329.790023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:45.529352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:45.199419Z","time spent":"329.925177ms","remote":"127.0.0.1:45914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:52:45.529631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.47122ms","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:6"} {"level":"info","ts":"2026-08-12T11:52:45.529746Z","caller":"traceutil/trace.go:171","msg":"trace[1005544059] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:25051; }","duration":"199.626985ms","start":"2026-08-12T11:52:45.330091Z","end":"2026-08-12T11:52:45.529718Z","steps":["trace[1005544059] 'agreement among raft nodes before linearized reading' (duration: 199.435109ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:45.709172Z","caller":"traceutil/trace.go:171","msg":"trace[1998470135] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"111.279501ms","start":"2026-08-12T11:52:45.597863Z","end":"2026-08-12T11:52:45.709142Z","steps":["trace[1998470135] 'process raft request' (duration: 111.072246ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:46.065491Z","caller":"traceutil/trace.go:171","msg":"trace[1967197215] linearizableReadLoop","detail":"{readStateIndex:25872; appliedIndex:25871; }","duration":"323.269498ms","start":"2026-08-12T11:52:45.742193Z","end":"2026-08-12T11:52:46.065463Z","steps":["trace[1967197215] 'read index received' (duration: 323.197346ms)","trace[1967197215] 'applied index is now lower than readState.Index' (duration: 70.662µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:46.065598Z","caller":"traceutil/trace.go:171","msg":"trace[1938247537] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"327.702354ms","start":"2026-08-12T11:52:45.737845Z","end":"2026-08-12T11:52:46.065547Z","steps":["trace[1938247537] 'process raft request' (duration: 327.409166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:46.065739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.499485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T11:52:46.065836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:45.737811Z","time spent":"327.898889ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:52:46.06585Z","caller":"traceutil/trace.go:171","msg":"trace[1200057783] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25053; }","duration":"323.636268ms","start":"2026-08-12T11:52:45.742185Z","end":"2026-08-12T11:52:46.065822Z","steps":["trace[1200057783] 'agreement among raft nodes before linearized reading' (duration: 323.440473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:46.065901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:45.742135Z","time spent":"323.754772ms","remote":"127.0.0.1:39260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"info","ts":"2026-08-12T11:52:46.072795Z","caller":"traceutil/trace.go:171","msg":"trace[1619108657] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"141.503658ms","start":"2026-08-12T11:52:45.931271Z","end":"2026-08-12T11:52:46.072775Z","steps":["trace[1619108657] 'process raft request' (duration: 141.341483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:46.073154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.075248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:52:46.073298Z","caller":"traceutil/trace.go:171","msg":"trace[9703761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25055; }","duration":"218.281244ms","start":"2026-08-12T11:52:45.854993Z","end":"2026-08-12T11:52:46.073274Z","steps":["trace[9703761] 'agreement among raft nodes before linearized reading' (duration: 218.011416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:46.29466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.897079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:52:46.294773Z","caller":"traceutil/trace.go:171","msg":"trace[49739904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25055; }","duration":"220.053664ms","start":"2026-08-12T11:52:46.074693Z","end":"2026-08-12T11:52:46.294747Z","steps":["trace[49739904] 'range keys from in-memory index tree' (duration: 219.820927ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:46.520188Z","caller":"traceutil/trace.go:171","msg":"trace[333667877] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"145.509882ms","start":"2026-08-12T11:52:46.374641Z","end":"2026-08-12T11:52:46.520151Z","steps":["trace[333667877] 'process raft request' (duration: 145.259775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:46.806004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.466627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-12T11:52:46.806074Z","caller":"traceutil/trace.go:171","msg":"trace[48450480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25056; }","duration":"124.564869ms","start":"2026-08-12T11:52:46.681493Z","end":"2026-08-12T11:52:46.806058Z","steps":["trace[48450480] 'range keys from in-memory index tree' (duration: 124.326233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:46.806079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.106165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-12T11:52:46.806116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.103719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:52:46.806187Z","caller":"traceutil/trace.go:171","msg":"trace[311688985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25056; }","duration":"125.289309ms","start":"2026-08-12T11:52:46.680875Z","end":"2026-08-12T11:52:46.806165Z","steps":["trace[311688985] 'range keys from in-memory index tree' (duration: 124.893248ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:46.806227Z","caller":"traceutil/trace.go:171","msg":"trace[256760836] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25056; }","duration":"170.260953ms","start":"2026-08-12T11:52:46.635939Z","end":"2026-08-12T11:52:46.8062Z","steps":["trace[256760836] 'range keys from in-memory index tree' (duration: 170.035927ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:46.8215Z","caller":"traceutil/trace.go:171","msg":"trace[1141062293] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"119.35005ms","start":"2026-08-12T11:52:46.702119Z","end":"2026-08-12T11:52:46.82147Z","steps":["trace[1141062293] 'process raft request' (duration: 119.095663ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:46.821512Z","caller":"traceutil/trace.go:171","msg":"trace[571581525] linearizableReadLoop","detail":"{readStateIndex:25876; appliedIndex:25876; }","duration":"104.966873ms","start":"2026-08-12T11:52:46.716507Z","end":"2026-08-12T11:52:46.821474Z","steps":["trace[571581525] 'read index received' (duration: 104.950983ms)","trace[571581525] 'applied index is now lower than readState.Index' (duration: 12.75µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:52:46.821893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.347295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-12T11:52:46.82195Z","caller":"traceutil/trace.go:171","msg":"trace[346509273] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25057; }","duration":"105.500429ms","start":"2026-08-12T11:52:46.716435Z","end":"2026-08-12T11:52:46.821936Z","steps":["trace[346509273] 'agreement among raft nodes before linearized reading' (duration: 105.148378ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:47.155918Z","caller":"traceutil/trace.go:171","msg":"trace[1963540159] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"344.610333ms","start":"2026-08-12T11:52:46.81128Z","end":"2026-08-12T11:52:47.155891Z","steps":["trace[1963540159] 'process raft request' (duration: 344.51855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:47.156103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:46.811248Z","time spent":"344.752297ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:52:47.156521Z","caller":"traceutil/trace.go:171","msg":"trace[1915535114] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"345.289721ms","start":"2026-08-12T11:52:46.811218Z","end":"2026-08-12T11:52:47.156508Z","steps":["trace[1915535114] 'process raft request' (duration: 344.418857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:47.156595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:46.811189Z","time spent":"345.360283ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:52:47.156976Z","caller":"traceutil/trace.go:171","msg":"trace[478520565] linearizableReadLoop","detail":"{readStateIndex:25878; appliedIndex:25878; }","duration":"335.045021ms","start":"2026-08-12T11:52:46.821919Z","end":"2026-08-12T11:52:47.156964Z","steps":["trace[478520565] 'read index received' (duration: 335.040791ms)","trace[478520565] 'applied index is now lower than readState.Index' (duration: 3.49µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:52:47.157078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.713223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:52:47.157118Z","caller":"traceutil/trace.go:171","msg":"trace[1395981123] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25059; }","duration":"346.804625ms","start":"2026-08-12T11:52:46.810301Z","end":"2026-08-12T11:52:47.157106Z","steps":["trace[1395981123] 'agreement among raft nodes before linearized reading' (duration: 346.736923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:47.157153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:46.810274Z","time spent":"346.871977ms","remote":"127.0.0.1:46014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-08-12T11:52:47.175384Z","caller":"traceutil/trace.go:171","msg":"trace[1106473701] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"347.933807ms","start":"2026-08-12T11:52:46.827421Z","end":"2026-08-12T11:52:47.175355Z","steps":["trace[1106473701] 'process raft request' (duration: 347.586667ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:47.175432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.977915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T11:52:47.175535Z","caller":"traceutil/trace.go:171","msg":"trace[532496175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25060; }","duration":"225.140409ms","start":"2026-08-12T11:52:46.950373Z","end":"2026-08-12T11:52:47.175513Z","steps":["trace[532496175] 'agreement among raft nodes before linearized reading' (duration: 224.869351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:47.175608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:46.827387Z","time spent":"348.104311ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:52:47.539188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.708271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:52:47.539319Z","caller":"traceutil/trace.go:171","msg":"trace[1858135020] linearizableReadLoop","detail":"{readStateIndex:25880; appliedIndex:25879; }","duration":"349.697897ms","start":"2026-08-12T11:52:47.189602Z","end":"2026-08-12T11:52:47.5393Z","steps":["trace[1858135020] 'read index received' (duration: 145.654666ms)","trace[1858135020] 'applied index is now lower than readState.Index' (duration: 204.041781ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:47.539398Z","caller":"traceutil/trace.go:171","msg":"trace[2135881268] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"352.238339ms","start":"2026-08-12T11:52:47.187103Z","end":"2026-08-12T11:52:47.539342Z","steps":["trace[2135881268] 'process raft request' (duration: 148.275481ms)","trace[2135881268] 'compare' (duration: 203.581127ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:52:47.539621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:47.18709Z","time spent":"352.407544ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:52:47.540527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.900531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-08-12T11:52:47.540617Z","caller":"traceutil/trace.go:171","msg":"trace[174141761] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25061; }","duration":"351.023944ms","start":"2026-08-12T11:52:47.189573Z","end":"2026-08-12T11:52:47.540597Z","steps":["trace[174141761] 'agreement among raft nodes before linearized reading' (duration: 349.80245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:47.540681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:47.18956Z","time spent":"351.111957ms","remote":"127.0.0.1:45842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5675,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-12T11:52:47.540931Z","caller":"traceutil/trace.go:171","msg":"trace[1189089634] transaction","detail":"{read_only:false; response_revision:25062; number_of_response:1; }","duration":"162.121483ms","start":"2026-08-12T11:52:47.378784Z","end":"2026-08-12T11:52:47.540905Z","steps":["trace[1189089634] 'process raft request' (duration: 161.952988ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:47.843525Z","caller":"traceutil/trace.go:171","msg":"trace[1853641585] linearizableReadLoop","detail":"{readStateIndex:25883; appliedIndex:25881; }","duration":"304.153736ms","start":"2026-08-12T11:52:47.539349Z","end":"2026-08-12T11:52:47.843503Z","steps":["trace[1853641585] 'read index received' (duration: 1.835791ms)","trace[1853641585] 'applied index is now lower than readState.Index' (duration: 302.317025ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:47.84358Z","caller":"traceutil/trace.go:171","msg":"trace[188311836] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"463.979673ms","start":"2026-08-12T11:52:47.379574Z","end":"2026-08-12T11:52:47.843554Z","steps":["trace[188311836] 'process raft request' (duration: 463.740277ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:47.843664Z","caller":"traceutil/trace.go:171","msg":"trace[1841434320] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"462.646876ms","start":"2026-08-12T11:52:47.380961Z","end":"2026-08-12T11:52:47.843608Z","steps":["trace[1841434320] 'process raft request' (duration: 462.501492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:47.84375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:47.379548Z","time spent":"464.088227ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:52:47.843725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.853397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T11:52:47.84389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:47.380922Z","time spent":"462.870722ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:52:47.843948Z","caller":"traceutil/trace.go:171","msg":"trace[1846591951] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25064; }","duration":"382.160016ms","start":"2026-08-12T11:52:47.461773Z","end":"2026-08-12T11:52:47.843933Z","steps":["trace[1846591951] 'agreement among raft nodes before linearized reading' (duration: 381.855537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:47.843988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:47.461738Z","time spent":"382.234488ms","remote":"127.0.0.1:35426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:52:47.843742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.060523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-12T11:52:47.844119Z","caller":"traceutil/trace.go:171","msg":"trace[20951359] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25064; }","duration":"466.433483ms","start":"2026-08-12T11:52:47.377637Z","end":"2026-08-12T11:52:47.84407Z","steps":["trace[20951359] 'agreement among raft nodes before linearized reading' (duration: 465.97415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:47.84426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:47.377615Z","time spent":"466.589779ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-12T11:52:48.149117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.473522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:52:48.149322Z","caller":"traceutil/trace.go:171","msg":"trace[1600655534] linearizableReadLoop","detail":"{readStateIndex:25884; appliedIndex:25883; }","duration":"305.719921ms","start":"2026-08-12T11:52:47.843584Z","end":"2026-08-12T11:52:48.149304Z","steps":["trace[1600655534] 'read index received' (duration: 40.92546ms)","trace[1600655534] 'applied index is now lower than readState.Index' (duration: 264.793211ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:48.149418Z","caller":"traceutil/trace.go:171","msg":"trace[1519848954] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"433.789067ms","start":"2026-08-12T11:52:47.715615Z","end":"2026-08-12T11:52:48.149404Z","steps":["trace[1519848954] 'process raft request' (duration: 168.876503ms)","trace[1519848954] 'compare' (duration: 264.165153ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:52:48.149529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:47.715578Z","time spent":"433.87622ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:52:48.149823Z","caller":"traceutil/trace.go:171","msg":"trace[1741899223] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"300.188145ms","start":"2026-08-12T11:52:47.849598Z","end":"2026-08-12T11:52:48.149786Z","steps":["trace[1741899223] 'process raft request' (duration: 299.656869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:48.149903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.63774ms","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:6"} {"level":"warn","ts":"2026-08-12T11:52:48.149984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:47.849571Z","time spent":"300.328709ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:52:48.150002Z","caller":"traceutil/trace.go:171","msg":"trace[200496369] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25066; }","duration":"502.803725ms","start":"2026-08-12T11:52:47.647176Z","end":"2026-08-12T11:52:48.14998Z","steps":["trace[200496369] 'agreement among raft nodes before linearized reading' (duration: 502.29847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:48.150051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:47.647157Z","time spent":"502.883636ms","remote":"127.0.0.1:35262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:52:48.162228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.278133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:52:48.162269Z","caller":"traceutil/trace.go:171","msg":"trace[722256300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25068; }","duration":"308.357645ms","start":"2026-08-12T11:52:47.853901Z","end":"2026-08-12T11:52:48.162258Z","steps":["trace[722256300] 'agreement among raft nodes before linearized reading' (duration: 308.246142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:48.162289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:47.85388Z","time spent":"308.404837ms","remote":"127.0.0.1:45868","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-08-12T11:52:49.056733Z","caller":"traceutil/trace.go:171","msg":"trace[909465740] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"229.011407ms","start":"2026-08-12T11:52:48.827689Z","end":"2026-08-12T11:52:49.0567Z","steps":["trace[909465740] 'process raft request' (duration: 228.796261ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:49.056865Z","caller":"traceutil/trace.go:171","msg":"trace[438252397] linearizableReadLoop","detail":"{readStateIndex:25889; appliedIndex:25889; }","duration":"202.161847ms","start":"2026-08-12T11:52:48.854667Z","end":"2026-08-12T11:52:49.056829Z","steps":["trace[438252397] 'read index received' (duration: 202.148216ms)","trace[438252397] 'applied index is now lower than readState.Index' (duration: 11.101µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:52:49.057028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.339131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:52:49.057065Z","caller":"traceutil/trace.go:171","msg":"trace[1327966963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25070; }","duration":"202.438894ms","start":"2026-08-12T11:52:48.854615Z","end":"2026-08-12T11:52:49.057054Z","steps":["trace[1327966963] 'agreement among raft nodes before linearized reading' (duration: 202.330991ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:49.058593Z","caller":"traceutil/trace.go:171","msg":"trace[1299341495] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"156.410341ms","start":"2026-08-12T11:52:48.902152Z","end":"2026-08-12T11:52:49.058563Z","steps":["trace[1299341495] 'process raft request' (duration: 156.204706ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:49.376151Z","caller":"traceutil/trace.go:171","msg":"trace[373746365] transaction","detail":"{read_only:false; response_revision:25073; number_of_response:1; }","duration":"162.39504ms","start":"2026-08-12T11:52:49.213726Z","end":"2026-08-12T11:52:49.376121Z","steps":["trace[373746365] 'process raft request' (duration: 157.189124ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:49.376588Z","caller":"traceutil/trace.go:171","msg":"trace[1017897218] transaction","detail":"{read_only:false; response_revision:25074; number_of_response:1; }","duration":"161.992329ms","start":"2026-08-12T11:52:49.214558Z","end":"2026-08-12T11:52:49.37655Z","steps":["trace[1017897218] 'process raft request' (duration: 161.437763ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:49.736141Z","caller":"traceutil/trace.go:171","msg":"trace[160706814] linearizableReadLoop","detail":"{readStateIndex:25894; appliedIndex:25893; }","duration":"185.473984ms","start":"2026-08-12T11:52:49.550645Z","end":"2026-08-12T11:52:49.736119Z","steps":["trace[160706814] 'read index received' (duration: 185.182276ms)","trace[160706814] 'applied index is now lower than readState.Index' (duration: 290.538µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:49.736279Z","caller":"traceutil/trace.go:171","msg":"trace[1429722114] transaction","detail":"{read_only:false; response_revision:25075; number_of_response:1; }","duration":"189.064686ms","start":"2026-08-12T11:52:49.547169Z","end":"2026-08-12T11:52:49.736234Z","steps":["trace[1429722114] 'process raft request' (duration: 188.718836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:49.736501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.842255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T11:52:49.736544Z","caller":"traceutil/trace.go:171","msg":"trace[713936468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25075; }","duration":"185.938326ms","start":"2026-08-12T11:52:49.550593Z","end":"2026-08-12T11:52:49.736532Z","steps":["trace[713936468] 'agreement among raft nodes before linearized reading' (duration: 185.648648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:49.736622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.431343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-08-12T11:52:49.73674Z","caller":"traceutil/trace.go:171","msg":"trace[259848124] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25075; }","duration":"107.596157ms","start":"2026-08-12T11:52:49.629116Z","end":"2026-08-12T11:52:49.736712Z","steps":["trace[259848124] 'agreement among raft nodes before linearized reading' (duration: 107.244938ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:50.005228Z","caller":"traceutil/trace.go:171","msg":"trace[414335222] linearizableReadLoop","detail":"{readStateIndex:25897; appliedIndex:25895; }","duration":"151.674097ms","start":"2026-08-12T11:52:49.853518Z","end":"2026-08-12T11:52:50.005192Z","steps":["trace[414335222] 'read index received' (duration: 56.015677ms)","trace[414335222] 'applied index is now lower than readState.Index' (duration: 95.65696ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:50.005339Z","caller":"traceutil/trace.go:171","msg":"trace[1770915270] transaction","detail":"{read_only:false; response_revision:25078; number_of_response:1; }","duration":"154.445045ms","start":"2026-08-12T11:52:49.850845Z","end":"2026-08-12T11:52:50.00529Z","steps":["trace[1770915270] 'process raft request' (duration: 154.104016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:50.00549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.919844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:52:50.005592Z","caller":"traceutil/trace.go:171","msg":"trace[681988025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25078; }","duration":"152.100669ms","start":"2026-08-12T11:52:49.853468Z","end":"2026-08-12T11:52:50.005569Z","steps":["trace[681988025] 'agreement among raft nodes before linearized reading' (duration: 151.850012ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:50.594278Z","caller":"traceutil/trace.go:171","msg":"trace[1464899848] transaction","detail":"{read_only:false; response_revision:25081; number_of_response:1; }","duration":"426.729029ms","start":"2026-08-12T11:52:50.167522Z","end":"2026-08-12T11:52:50.594251Z","steps":["trace[1464899848] 'process raft request' (duration: 426.669467ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:50.594394Z","caller":"traceutil/trace.go:171","msg":"trace[31923174] transaction","detail":"{read_only:false; response_revision:25080; number_of_response:1; }","duration":"427.066188ms","start":"2026-08-12T11:52:50.167309Z","end":"2026-08-12T11:52:50.594375Z","steps":["trace[31923174] 'process raft request' (duration: 426.710138ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:50.594478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:50.16728Z","time spent":"427.14653ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:52:50.594475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:50.167483Z","time spent":"426.890183ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:52:50.594587Z","caller":"traceutil/trace.go:171","msg":"trace[1119496907] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"438.327887ms","start":"2026-08-12T11:52:50.156226Z","end":"2026-08-12T11:52:50.594554Z","steps":["trace[1119496907] 'process raft request' (duration: 354.190544ms)","trace[1119496907] 'compare' (duration: 83.226998ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:52:50.594761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:50.156213Z","time spent":"438.45364ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:52:51.083789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.363656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:52:51.08403Z","caller":"traceutil/trace.go:171","msg":"trace[1413324227] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"854.853067ms","start":"2026-08-12T11:52:50.229136Z","end":"2026-08-12T11:52:51.083989Z","steps":["trace[1413324227] 'process raft request' (duration: 570.182902ms)","trace[1413324227] 'compare' (duration: 283.866732ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:52:51.084198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:50.229116Z","time spent":"854.993341ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:52:51.084261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.334386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-12T11:52:51.084062Z","caller":"traceutil/trace.go:171","msg":"trace[1766487252] linearizableReadLoop","detail":"{readStateIndex:25903; appliedIndex:25901; }","duration":"523.108209ms","start":"2026-08-12T11:52:50.560934Z","end":"2026-08-12T11:52:51.084042Z","steps":["trace[1766487252] 'read index received' (duration: 238.356982ms)","trace[1766487252] 'applied index is now lower than readState.Index' (duration: 284.750097ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:51.084296Z","caller":"traceutil/trace.go:171","msg":"trace[1322444261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25083; }","duration":"523.412048ms","start":"2026-08-12T11:52:50.560874Z","end":"2026-08-12T11:52:51.084286Z","steps":["trace[1322444261] 'agreement among raft nodes before linearized reading' (duration: 523.222013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:51.084333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:50.560836Z","time spent":"523.48992ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-12T11:52:51.084388Z","caller":"traceutil/trace.go:171","msg":"trace[1618022002] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"850.768212ms","start":"2026-08-12T11:52:50.233589Z","end":"2026-08-12T11:52:51.084357Z","steps":["trace[1618022002] 'process raft request' (duration: 850.317439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:51.084561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:50.233553Z","time spent":"850.922496ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:52:51.425908Z","caller":"traceutil/trace.go:171","msg":"trace[1700875357] linearizableReadLoop","detail":"{readStateIndex:25905; appliedIndex:25903; }","duration":"341.574106ms","start":"2026-08-12T11:52:51.08431Z","end":"2026-08-12T11:52:51.425884Z","steps":["trace[1700875357] 'read index received' (duration: 43.983406ms)","trace[1700875357] 'applied index is now lower than readState.Index' (duration: 297.5895ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:51.426051Z","caller":"traceutil/trace.go:171","msg":"trace[762255406] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"342.142212ms","start":"2026-08-12T11:52:51.083894Z","end":"2026-08-12T11:52:51.426036Z","steps":["trace[762255406] 'process raft request' (duration: 309.950011ms)","trace[762255406] 'compare' (duration: 31.470141ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:51.426037Z","caller":"traceutil/trace.go:171","msg":"trace[1618739607] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"146.116309ms","start":"2026-08-12T11:52:51.279874Z","end":"2026-08-12T11:52:51.425991Z","steps":["trace[1618739607] 'process raft request' (duration: 145.965575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:51.426169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:51.083866Z","time spent":"342.211595ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:52:51.426322Z","caller":"traceutil/trace.go:171","msg":"trace[1024398194] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"336.817551ms","start":"2026-08-12T11:52:51.089494Z","end":"2026-08-12T11:52:51.426311Z","steps":["trace[1024398194] 'process raft request' (duration: 336.244285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:51.426383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:51.089483Z","time spent":"336.873193ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:52:51.426542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.647645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:52:51.426573Z","caller":"traceutil/trace.go:171","msg":"trace[1963336600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25087; }","duration":"570.716587ms","start":"2026-08-12T11:52:50.855847Z","end":"2026-08-12T11:52:51.426564Z","steps":["trace[1963336600] 'agreement among raft nodes before linearized reading' (duration: 570.601734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:51.426599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:50.855828Z","time spent":"570.765949ms","remote":"127.0.0.1:45868","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-08-12T11:52:51.426618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.246881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-08-12T11:52:51.426724Z","caller":"traceutil/trace.go:171","msg":"trace[1130048471] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25087; }","duration":"339.405104ms","start":"2026-08-12T11:52:51.08728Z","end":"2026-08-12T11:52:51.426685Z","steps":["trace[1130048471] 'agreement among raft nodes before linearized reading' (duration: 339.2328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:51.426774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:51.087252Z","time spent":"339.513828ms","remote":"127.0.0.1:45984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-08-12T11:52:51.42701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.817406ms","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-08-12T11:52:51.427067Z","caller":"traceutil/trace.go:171","msg":"trace[914449473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25087; }","duration":"184.937829ms","start":"2026-08-12T11:52:51.242115Z","end":"2026-08-12T11:52:51.427053Z","steps":["trace[914449473] 'agreement among raft nodes before linearized reading' (duration: 184.820485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:51.427216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.288678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274802"} {"level":"info","ts":"2026-08-12T11:52:51.427254Z","caller":"traceutil/trace.go:171","msg":"trace[2013329420] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25087; }","duration":"617.37876ms","start":"2026-08-12T11:52:50.809865Z","end":"2026-08-12T11:52:51.427244Z","steps":["trace[2013329420] 'agreement among raft nodes before linearized reading' (duration: 617.029019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:51.427278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:50.809842Z","time spent":"617.427682ms","remote":"127.0.0.1:46258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":32,"response size":274825,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"info","ts":"2026-08-12T11:52:51.642928Z","caller":"traceutil/trace.go:171","msg":"trace[1969411438] linearizableReadLoop","detail":"{readStateIndex:25909; appliedIndex:25907; }","duration":"210.32908ms","start":"2026-08-12T11:52:51.432567Z","end":"2026-08-12T11:52:51.642896Z","steps":["trace[1969411438] 'read index received' (duration: 133.470172ms)","trace[1969411438] 'applied index is now lower than readState.Index' (duration: 76.857607ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:51.643338Z","caller":"traceutil/trace.go:171","msg":"trace[344532917] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"211.626436ms","start":"2026-08-12T11:52:51.431651Z","end":"2026-08-12T11:52:51.643277Z","steps":["trace[344532917] 'process raft request' (duration: 134.400948ms)","trace[344532917] 'compare' (duration: 76.559399ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:51.643406Z","caller":"traceutil/trace.go:171","msg":"trace[511198526] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"211.351628ms","start":"2026-08-12T11:52:51.432019Z","end":"2026-08-12T11:52:51.643371Z","steps":["trace[511198526] 'process raft request' (duration: 210.784392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:51.643483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.900845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-12T11:52:51.643482Z","caller":"traceutil/trace.go:171","msg":"trace[1803419146] transaction","detail":"{read_only:false; response_revision:25090; number_of_response:1; }","duration":"210.664118ms","start":"2026-08-12T11:52:51.432772Z","end":"2026-08-12T11:52:51.643436Z","steps":["trace[1803419146] 'process raft request' (duration: 210.086211ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:51.643542Z","caller":"traceutil/trace.go:171","msg":"trace[673476000] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25090; }","duration":"211.013658ms","start":"2026-08-12T11:52:51.432512Z","end":"2026-08-12T11:52:51.643526Z","steps":["trace[673476000] 'agreement among raft nodes before linearized reading' (duration: 210.852094ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:51.643519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.127863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:52:51.643597Z","caller":"traceutil/trace.go:171","msg":"trace[1329686684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25090; }","duration":"210.247347ms","start":"2026-08-12T11:52:51.433332Z","end":"2026-08-12T11:52:51.643579Z","steps":["trace[1329686684] 'agreement among raft nodes before linearized reading' (duration: 210.076862ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:55.35248Z","caller":"traceutil/trace.go:171","msg":"trace[1252993930] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"247.761739ms","start":"2026-08-12T11:52:55.10465Z","end":"2026-08-12T11:52:55.352412Z","steps":["trace[1252993930] 'process raft request' (duration: 247.525023ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:55.614399Z","caller":"traceutil/trace.go:171","msg":"trace[510112702] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"473.753901ms","start":"2026-08-12T11:52:55.140592Z","end":"2026-08-12T11:52:55.614346Z","steps":["trace[510112702] 'process raft request' (duration: 472.946608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:55.614673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:55.140557Z","time spent":"473.958727ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:52:55.750122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.25502ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-12T11:52:55.750287Z","caller":"traceutil/trace.go:171","msg":"trace[1292861639] linearizableReadLoop","detail":"{readStateIndex:25934; appliedIndex:25931; }","duration":"602.527299ms","start":"2026-08-12T11:52:55.147726Z","end":"2026-08-12T11:52:55.750253Z","steps":["trace[1292861639] 'read index received' (duration: 204.424352ms)","trace[1292861639] 'applied index is now lower than readState.Index' (duration: 398.100417ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:52:55.750541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.778376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:52:55.750602Z","caller":"traceutil/trace.go:171","msg":"trace[225334793] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25113; }","duration":"602.92356ms","start":"2026-08-12T11:52:55.147655Z","end":"2026-08-12T11:52:55.750578Z","steps":["trace[225334793] 'agreement among raft nodes before linearized reading' (duration: 602.758786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:55.75058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.110246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:52:55.750682Z","caller":"traceutil/trace.go:171","msg":"trace[2070178712] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:25113; }","duration":"567.303182ms","start":"2026-08-12T11:52:55.183352Z","end":"2026-08-12T11:52:55.750655Z","steps":["trace[2070178712] 'agreement among raft nodes before linearized reading' (duration: 567.103886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:55.75067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:55.147602Z","time spent":"603.053434ms","remote":"127.0.0.1:39202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:52:55.750731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:55.183329Z","time spent":"567.391514ms","remote":"127.0.0.1:46146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":20,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:52:55.751078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.322104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"warn","ts":"2026-08-12T11:52:55.751154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.768807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:52:55.751178Z","caller":"traceutil/trace.go:171","msg":"trace[157887483] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25113; }","duration":"338.479949ms","start":"2026-08-12T11:52:55.412661Z","end":"2026-08-12T11:52:55.751141Z","steps":["trace[157887483] 'agreement among raft nodes before linearized reading' (duration: 338.265683ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:55.751197Z","caller":"traceutil/trace.go:171","msg":"trace[1858853354] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:25113; }","duration":"212.837579ms","start":"2026-08-12T11:52:55.538345Z","end":"2026-08-12T11:52:55.751183Z","steps":["trace[1858853354] 'agreement among raft nodes before linearized reading' (duration: 212.765817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:55.751223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:55.412637Z","time spent":"338.576302ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-12T11:52:55.751227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.29534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-12T11:52:55.751267Z","caller":"traceutil/trace.go:171","msg":"trace[2005413659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25113; }","duration":"310.382743ms","start":"2026-08-12T11:52:55.440872Z","end":"2026-08-12T11:52:55.751255Z","steps":["trace[2005413659] 'agreement among raft nodes before linearized reading' (duration: 310.285281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:55.751293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:55.440841Z","time spent":"310.445464ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T11:52:55.751081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.558963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:52:55.751406Z","caller":"traceutil/trace.go:171","msg":"trace[1681156998] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25113; }","duration":"403.941103ms","start":"2026-08-12T11:52:55.347429Z","end":"2026-08-12T11:52:55.75137Z","steps":["trace[1681156998] 'agreement among raft nodes before linearized reading' (duration: 403.49958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:55.751484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:52:55.347403Z","time spent":"404.055416ms","remote":"127.0.0.1:46054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":77,"response size":31,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-08-12T11:52:55.936574Z","caller":"traceutil/trace.go:171","msg":"trace[48468781] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"180.644387ms","start":"2026-08-12T11:52:55.755909Z","end":"2026-08-12T11:52:55.936553Z","steps":["trace[48468781] 'process raft request' (duration: 180.477042ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:55.981649Z","caller":"traceutil/trace.go:171","msg":"trace[1429965514] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"225.520168ms","start":"2026-08-12T11:52:55.75611Z","end":"2026-08-12T11:52:55.98163Z","steps":["trace[1429965514] 'process raft request' (duration: 225.444876ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:55.981732Z","caller":"traceutil/trace.go:171","msg":"trace[1046875221] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"225.666322ms","start":"2026-08-12T11:52:55.756021Z","end":"2026-08-12T11:52:55.981687Z","steps":["trace[1046875221] 'process raft request' (duration: 225.391224ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:55.982062Z","caller":"traceutil/trace.go:171","msg":"trace[1223790877] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"225.187809ms","start":"2026-08-12T11:52:55.756862Z","end":"2026-08-12T11:52:55.98205Z","steps":["trace[1223790877] 'process raft request' (duration: 224.730506ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:55.982125Z","caller":"traceutil/trace.go:171","msg":"trace[1814055443] linearizableReadLoop","detail":"{readStateIndex:25940; appliedIndex:25935; }","duration":"208.097074ms","start":"2026-08-12T11:52:55.773998Z","end":"2026-08-12T11:52:55.982095Z","steps":["trace[1814055443] 'read index received' (duration: 162.685898ms)","trace[1814055443] 'applied index is now lower than readState.Index' (duration: 45.410296ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:52:55.982182Z","caller":"traceutil/trace.go:171","msg":"trace[1483459307] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"225.155479ms","start":"2026-08-12T11:52:55.757018Z","end":"2026-08-12T11:52:55.982173Z","steps":["trace[1483459307] 'process raft request' (duration: 224.859359ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:52:55.982235Z","caller":"traceutil/trace.go:171","msg":"trace[2099887436] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"214.990681ms","start":"2026-08-12T11:52:55.767216Z","end":"2026-08-12T11:52:55.982206Z","steps":["trace[2099887436] 'process raft request' (duration: 214.821715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:55.982398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.373554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T11:52:55.982443Z","caller":"traceutil/trace.go:171","msg":"trace[977359995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25119; }","duration":"208.472737ms","start":"2026-08-12T11:52:55.773955Z","end":"2026-08-12T11:52:55.982428Z","steps":["trace[977359995] 'agreement among raft nodes before linearized reading' (duration: 208.282391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:52:55.982982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.917582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:52:55.983026Z","caller":"traceutil/trace.go:171","msg":"trace[1066432576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25120; }","duration":"130.027064ms","start":"2026-08-12T11:52:55.852989Z","end":"2026-08-12T11:52:55.983016Z","steps":["trace[1066432576] 'agreement among raft nodes before linearized reading' (duration: 129.920011ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:06.344188Z","caller":"traceutil/trace.go:171","msg":"trace[531927404] transaction","detail":"{read_only:false; response_revision:25249; number_of_response:1; }","duration":"327.441076ms","start":"2026-08-12T11:53:06.016704Z","end":"2026-08-12T11:53:06.344145Z","steps":["trace[531927404] 'process raft request' (duration: 327.191159ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:06.344212Z","caller":"traceutil/trace.go:171","msg":"trace[1175125257] linearizableReadLoop","detail":"{readStateIndex:26073; appliedIndex:26071; }","duration":"313.006087ms","start":"2026-08-12T11:53:06.031177Z","end":"2026-08-12T11:53:06.344183Z","steps":["trace[1175125257] 'read index received' (duration: 51.17378ms)","trace[1175125257] 'applied index is now lower than readState.Index' (duration: 261.830867ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:06.344318Z","caller":"traceutil/trace.go:171","msg":"trace[976501832] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"284.54429ms","start":"2026-08-12T11:53:06.059733Z","end":"2026-08-12T11:53:06.344277Z","steps":["trace[976501832] 'process raft request' (duration: 284.378836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:06.344389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:06.016692Z","time spent":"327.58822ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:53:06.344518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.256043ms","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-08-12T11:53:06.344547Z","caller":"traceutil/trace.go:171","msg":"trace[1848159047] transaction","detail":"{read_only:false; response_revision:25250; number_of_response:1; }","duration":"325.754029ms","start":"2026-08-12T11:53:06.01878Z","end":"2026-08-12T11:53:06.344534Z","steps":["trace[1848159047] 'process raft request' (duration: 325.279974ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:06.344573Z","caller":"traceutil/trace.go:171","msg":"trace[879260664] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25251; }","duration":"313.437399ms","start":"2026-08-12T11:53:06.031116Z","end":"2026-08-12T11:53:06.344554Z","steps":["trace[879260664] 'agreement among raft nodes before linearized reading' (duration: 313.214182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:06.344614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:06.031085Z","time spent":"313.517751ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-12T11:53:06.3447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:06.018775Z","time spent":"325.799101ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:53:06.344685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.10604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-12T11:53:06.344819Z","caller":"traceutil/trace.go:171","msg":"trace[1891108303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25251; }","duration":"313.297736ms","start":"2026-08-12T11:53:06.031489Z","end":"2026-08-12T11:53:06.344786Z","steps":["trace[1891108303] 'agreement among raft nodes before linearized reading' (duration: 312.933894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:06.344891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:06.031476Z","time spent":"313.400058ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-12T11:53:06.344885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.780428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-12T11:53:06.344965Z","caller":"traceutil/trace.go:171","msg":"trace[1693521526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25251; }","duration":"308.905232ms","start":"2026-08-12T11:53:06.036038Z","end":"2026-08-12T11:53:06.344943Z","steps":["trace[1693521526] 'agreement among raft nodes before linearized reading' (duration: 308.685056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:06.345009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:06.036015Z","time spent":"308.982894ms","remote":"127.0.0.1:46086","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-08-12T11:53:06.34512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.45ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-12T11:53:06.345735Z","caller":"traceutil/trace.go:171","msg":"trace[909333662] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25251; }","duration":"314.010696ms","start":"2026-08-12T11:53:06.031139Z","end":"2026-08-12T11:53:06.34515Z","steps":["trace[909333662] 'agreement among raft nodes before linearized reading' (duration: 313.177451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:06.345825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:53:06.031127Z","time spent":"314.674244ms","remote":"127.0.0.1:46086","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-08-12T11:53:06.531808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.369746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:53:06.531964Z","caller":"traceutil/trace.go:171","msg":"trace[407401599] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"182.754937ms","start":"2026-08-12T11:53:06.349181Z","end":"2026-08-12T11:53:06.531936Z","steps":["trace[407401599] 'process raft request' (duration: 36.135583ms)","trace[407401599] 'compare' (duration: 146.081949ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:06.538643Z","caller":"traceutil/trace.go:171","msg":"trace[1378005818] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"188.85322ms","start":"2026-08-12T11:53:06.349754Z","end":"2026-08-12T11:53:06.538608Z","steps":["trace[1378005818] 'process raft request' (duration: 188.621564ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:06.538957Z","caller":"traceutil/trace.go:171","msg":"trace[817665892] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"188.84097ms","start":"2026-08-12T11:53:06.350088Z","end":"2026-08-12T11:53:06.538929Z","steps":["trace[817665892] 'process raft request' (duration: 188.454799ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:06.53901Z","caller":"traceutil/trace.go:171","msg":"trace[1684889417] linearizableReadLoop","detail":"{readStateIndex:26079; appliedIndex:26074; }","duration":"182.050377ms","start":"2026-08-12T11:53:06.356942Z","end":"2026-08-12T11:53:06.538992Z","steps":["trace[1684889417] 'read index received' (duration: 28.363823ms)","trace[1684889417] 'applied index is now lower than readState.Index' (duration: 153.685714ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:06.539156Z","caller":"traceutil/trace.go:171","msg":"trace[2102694257] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"187.292346ms","start":"2026-08-12T11:53:06.351846Z","end":"2026-08-12T11:53:06.539138Z","steps":["trace[2102694257] 'process raft request' (duration: 186.961847ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:06.539149Z","caller":"traceutil/trace.go:171","msg":"trace[1212928208] transaction","detail":"{read_only:false; response_revision:25256; number_of_response:1; }","duration":"186.290158ms","start":"2026-08-12T11:53:06.352828Z","end":"2026-08-12T11:53:06.539118Z","steps":["trace[1212928208] 'process raft request' (duration: 186.090353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:06.540842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.860559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-12T11:53:06.540909Z","caller":"traceutil/trace.go:171","msg":"trace[749000435] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25256; }","duration":"183.977322ms","start":"2026-08-12T11:53:06.356921Z","end":"2026-08-12T11:53:06.540898Z","steps":["trace[749000435] 'agreement among raft nodes before linearized reading' (duration: 182.13321ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:06.55784Z","caller":"traceutil/trace.go:171","msg":"trace[1047874995] transaction","detail":"{read_only:false; response_revision:25257; number_of_response:1; }","duration":"106.311102ms","start":"2026-08-12T11:53:06.451512Z","end":"2026-08-12T11:53:06.557823Z","steps":["trace[1047874995] 'process raft request' (duration: 106.212239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:06.93663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.583758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:53:06.93685Z","caller":"traceutil/trace.go:171","msg":"trace[1971181941] linearizableReadLoop","detail":"{readStateIndex:26083; appliedIndex:26080; }","duration":"135.324213ms","start":"2026-08-12T11:53:06.801507Z","end":"2026-08-12T11:53:06.936831Z","steps":["trace[1971181941] 'read index received' (duration: 57.229291ms)","trace[1971181941] 'applied index is now lower than readState.Index' (duration: 78.093382ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:06.937152Z","caller":"traceutil/trace.go:171","msg":"trace[697753156] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"278.289074ms","start":"2026-08-12T11:53:06.658834Z","end":"2026-08-12T11:53:06.937124Z","steps":["trace[697753156] 'process raft request' (duration: 20.102299ms)","trace[697753156] 'compare' (duration: 257.406133ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:06.937277Z","caller":"traceutil/trace.go:171","msg":"trace[1066916591] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"275.660299ms","start":"2026-08-12T11:53:06.661256Z","end":"2026-08-12T11:53:06.936917Z","steps":["trace[1066916591] 'process raft request' (duration: 275.468093ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:06.937526Z","caller":"traceutil/trace.go:171","msg":"trace[1389743464] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"274.112676ms","start":"2026-08-12T11:53:06.663372Z","end":"2026-08-12T11:53:06.937484Z","steps":["trace[1389743464] 'process raft request' (duration: 273.417776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:06.937582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.086094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9cf729e2-c136-4c89-a10f-4056c8b91800\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-08-12T11:53:06.937614Z","caller":"traceutil/trace.go:171","msg":"trace[7958477] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9cf729e2-c136-4c89-a10f-4056c8b91800; range_end:; response_count:1; response_revision:25260; }","duration":"136.186007ms","start":"2026-08-12T11:53:06.801418Z","end":"2026-08-12T11:53:06.937604Z","steps":["trace[7958477] 'agreement among raft nodes before linearized reading' (duration: 135.537029ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:07.560467Z","caller":"traceutil/trace.go:171","msg":"trace[904058510] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"129.813038ms","start":"2026-08-12T11:53:07.430602Z","end":"2026-08-12T11:53:07.560415Z","steps":["trace[904058510] 'process raft request' (duration: 129.53788ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:07.777722Z","caller":"traceutil/trace.go:171","msg":"trace[1371560470] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"121.842501ms","start":"2026-08-12T11:53:07.655692Z","end":"2026-08-12T11:53:07.777534Z","steps":["trace[1371560470] 'process raft request' (duration: 108.775641ms)","trace[1371560470] 'compare' (duration: 12.916556ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:09.671759Z","caller":"traceutil/trace.go:171","msg":"trace[834084343] transaction","detail":"{read_only:false; response_revision:25276; number_of_response:1; }","duration":"102.542854ms","start":"2026-08-12T11:53:09.569164Z","end":"2026-08-12T11:53:09.671707Z","steps":["trace[834084343] 'process raft request' (duration: 102.245426ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:09.865504Z","caller":"traceutil/trace.go:171","msg":"trace[937705612] linearizableReadLoop","detail":"{readStateIndex:26100; appliedIndex:26099; }","duration":"193.879472ms","start":"2026-08-12T11:53:09.671601Z","end":"2026-08-12T11:53:09.865481Z","steps":["trace[937705612] 'read index received' (duration: 193.721398ms)","trace[937705612] 'applied index is now lower than readState.Index' (duration: 156.864µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:09.865587Z","caller":"traceutil/trace.go:171","msg":"trace[361132132] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"270.693617ms","start":"2026-08-12T11:53:09.594863Z","end":"2026-08-12T11:53:09.865557Z","steps":["trace[361132132] 'process raft request' (duration: 270.468881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:09.86579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.730469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:53:09.865878Z","caller":"traceutil/trace.go:171","msg":"trace[1806043835] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25277; }","duration":"258.932016ms","start":"2026-08-12T11:53:09.606927Z","end":"2026-08-12T11:53:09.865859Z","steps":["trace[1806043835] 'agreement among raft nodes before linearized reading' (duration: 258.643527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:09.867899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.530394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-cb0ea8d4-ea1e-427a-8b0e-c624354b547e\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-08-12T11:53:09.867954Z","caller":"traceutil/trace.go:171","msg":"trace[394320103] range","detail":"{range_begin:/registry/persistentvolumes/pvc-cb0ea8d4-ea1e-427a-8b0e-c624354b547e; range_end:; response_count:1; response_revision:25278; }","duration":"192.633047ms","start":"2026-08-12T11:53:09.675305Z","end":"2026-08-12T11:53:09.867938Z","steps":["trace[394320103] 'agreement among raft nodes before linearized reading' (duration: 192.435262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:14.663768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.858174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-08-12T11:53:14.663893Z","caller":"traceutil/trace.go:171","msg":"trace[1515385432] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25338; }","duration":"115.045919ms","start":"2026-08-12T11:53:14.548819Z","end":"2026-08-12T11:53:14.663865Z","steps":["trace[1515385432] 'range keys from in-memory index tree' (duration: 114.696619ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:14.775921Z","caller":"traceutil/trace.go:171","msg":"trace[1029041632] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"107.185746ms","start":"2026-08-12T11:53:14.668705Z","end":"2026-08-12T11:53:14.775891Z","steps":["trace[1029041632] 'process raft request' (duration: 106.976391ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:14.784303Z","caller":"traceutil/trace.go:171","msg":"trace[260049967] transaction","detail":"{read_only:false; response_revision:25342; number_of_response:1; }","duration":"114.930486ms","start":"2026-08-12T11:53:14.669353Z","end":"2026-08-12T11:53:14.784283Z","steps":["trace[260049967] 'process raft request' (duration: 114.880295ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:14.784425Z","caller":"traceutil/trace.go:171","msg":"trace[1622907683] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"115.139201ms","start":"2026-08-12T11:53:14.669228Z","end":"2026-08-12T11:53:14.784367Z","steps":["trace[1622907683] 'process raft request' (duration: 114.788282ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:14.784493Z","caller":"traceutil/trace.go:171","msg":"trace[1913703866] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"115.196353ms","start":"2026-08-12T11:53:14.66924Z","end":"2026-08-12T11:53:14.784436Z","steps":["trace[1913703866] 'process raft request' (duration: 114.931806ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:19.144082Z","caller":"traceutil/trace.go:171","msg":"trace[269432886] transaction","detail":"{read_only:false; response_revision:25376; number_of_response:1; }","duration":"138.095543ms","start":"2026-08-12T11:53:19.005957Z","end":"2026-08-12T11:53:19.144053Z","steps":["trace[269432886] 'process raft request' (duration: 138.04462ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:19.144408Z","caller":"traceutil/trace.go:171","msg":"trace[854422779] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"138.982518ms","start":"2026-08-12T11:53:19.005351Z","end":"2026-08-12T11:53:19.144333Z","steps":["trace[854422779] 'process raft request' (duration: 138.40638ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:22.372393Z","caller":"traceutil/trace.go:171","msg":"trace[9485848] linearizableReadLoop","detail":"{readStateIndex:26226; appliedIndex:26225; }","duration":"188.249063ms","start":"2026-08-12T11:53:22.184112Z","end":"2026-08-12T11:53:22.372362Z","steps":["trace[9485848] 'read index received' (duration: 187.574314ms)","trace[9485848] 'applied index is now lower than readState.Index' (duration: 673.679µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:22.372539Z","caller":"traceutil/trace.go:171","msg":"trace[1599220540] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"204.573285ms","start":"2026-08-12T11:53:22.167904Z","end":"2026-08-12T11:53:22.372477Z","steps":["trace[1599220540] 'process raft request' (duration: 204.05405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:22.372711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.5422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:53:22.372885Z","caller":"traceutil/trace.go:171","msg":"trace[1148995445] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25399; }","duration":"188.775017ms","start":"2026-08-12T11:53:22.184039Z","end":"2026-08-12T11:53:22.372814Z","steps":["trace[1148995445] 'agreement among raft nodes before linearized reading' (duration: 188.487489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:22.373059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.634719ms","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-08-12T11:53:22.373204Z","caller":"traceutil/trace.go:171","msg":"trace[1280174308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25399; }","duration":"167.853165ms","start":"2026-08-12T11:53:22.205297Z","end":"2026-08-12T11:53:22.373151Z","steps":["trace[1280174308] 'agreement among raft nodes before linearized reading' (duration: 167.384092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:22.576012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.990294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:53:22.576167Z","caller":"traceutil/trace.go:171","msg":"trace[1167831851] linearizableReadLoop","detail":"{readStateIndex:26227; appliedIndex:26226; }","duration":"148.719863ms","start":"2026-08-12T11:53:22.427422Z","end":"2026-08-12T11:53:22.576142Z","steps":["trace[1167831851] 'read index received' (duration: 70.592µs)","trace[1167831851] 'applied index is now lower than readState.Index' (duration: 148.647671ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:53:22.576377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.960839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-08-12T11:53:22.576376Z","caller":"traceutil/trace.go:171","msg":"trace[2539875] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"198.297848ms","start":"2026-08-12T11:53:22.378027Z","end":"2026-08-12T11:53:22.576325Z","steps":["trace[2539875] 'process raft request' (duration: 32.840581ms)","trace[2539875] 'compare' (duration: 164.719796ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:22.576425Z","caller":"traceutil/trace.go:171","msg":"trace[1527461603] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25400; }","duration":"149.046612ms","start":"2026-08-12T11:53:22.427359Z","end":"2026-08-12T11:53:22.576405Z","steps":["trace[1527461603] 'agreement among raft nodes before linearized reading' (duration: 148.848647ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:22.576574Z","caller":"traceutil/trace.go:171","msg":"trace[477935942] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"144.240367ms","start":"2026-08-12T11:53:22.432304Z","end":"2026-08-12T11:53:22.576544Z","steps":["trace[477935942] 'process raft request' (duration: 144.043002ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:22.577385Z","caller":"traceutil/trace.go:171","msg":"trace[200202056] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"140.966703ms","start":"2026-08-12T11:53:22.436387Z","end":"2026-08-12T11:53:22.577354Z","steps":["trace[200202056] 'process raft request' (duration: 140.715946ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:22.577585Z","caller":"traceutil/trace.go:171","msg":"trace[1328902989] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"141.063526ms","start":"2026-08-12T11:53:22.436472Z","end":"2026-08-12T11:53:22.577536Z","steps":["trace[1328902989] 'process raft request' (duration: 140.818479ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:26.699868Z","caller":"traceutil/trace.go:171","msg":"trace[48872181] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"107.478396ms","start":"2026-08-12T11:53:26.592352Z","end":"2026-08-12T11:53:26.69983Z","steps":["trace[48872181] 'process raft request' (duration: 107.25791ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:26.700247Z","caller":"traceutil/trace.go:171","msg":"trace[173016257] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"105.52804ms","start":"2026-08-12T11:53:26.594665Z","end":"2026-08-12T11:53:26.700193Z","steps":["trace[173016257] 'process raft request' (duration: 105.112028ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:26.838846Z","caller":"traceutil/trace.go:171","msg":"trace[1368080027] linearizableReadLoop","detail":"{readStateIndex:26261; appliedIndex:26260; }","duration":"102.415021ms","start":"2026-08-12T11:53:26.736407Z","end":"2026-08-12T11:53:26.838822Z","steps":["trace[1368080027] 'read index received' (duration: 102.291527ms)","trace[1368080027] 'applied index is now lower than readState.Index' (duration: 122.384µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:26.838975Z","caller":"traceutil/trace.go:171","msg":"trace[419242223] transaction","detail":"{read_only:false; response_revision:25433; number_of_response:1; }","duration":"133.407818ms","start":"2026-08-12T11:53:26.705553Z","end":"2026-08-12T11:53:26.838961Z","steps":["trace[419242223] 'process raft request' (duration: 133.004037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:26.839216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.802192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-08-12T11:53:26.839301Z","caller":"traceutil/trace.go:171","msg":"trace[1717194803] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25433; }","duration":"102.894824ms","start":"2026-08-12T11:53:26.736388Z","end":"2026-08-12T11:53:26.839283Z","steps":["trace[1717194803] 'agreement among raft nodes before linearized reading' (duration: 102.703659ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:26.991734Z","caller":"traceutil/trace.go:171","msg":"trace[713116206] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"128.783879ms","start":"2026-08-12T11:53:26.86291Z","end":"2026-08-12T11:53:26.991694Z","steps":["trace[713116206] 'process raft request' (duration: 115.580914ms)","trace[713116206] 'compare' (duration: 13.04736ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:34.877947Z","caller":"traceutil/trace.go:171","msg":"trace[380597216] linearizableReadLoop","detail":"{readStateIndex:26332; appliedIndex:26329; }","duration":"119.990639ms","start":"2026-08-12T11:53:34.757936Z","end":"2026-08-12T11:53:34.877927Z","steps":["trace[380597216] 'read index received' (duration: 4.611341ms)","trace[380597216] 'applied index is now lower than readState.Index' (duration: 115.378148ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:53:34.878297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.344229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-08-12T11:53:34.878389Z","caller":"traceutil/trace.go:171","msg":"trace[1976108112] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25502; }","duration":"120.469183ms","start":"2026-08-12T11:53:34.757896Z","end":"2026-08-12T11:53:34.878365Z","steps":["trace[1976108112] 'agreement among raft nodes before linearized reading' (duration: 120.231866ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:34.878627Z","caller":"traceutil/trace.go:171","msg":"trace[1732062709] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"143.960478ms","start":"2026-08-12T11:53:34.734652Z","end":"2026-08-12T11:53:34.878612Z","steps":["trace[1732062709] 'process raft request' (duration: 131.060173ms)","trace[1732062709] 'compare' (duration: 11.791654ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:34.878655Z","caller":"traceutil/trace.go:171","msg":"trace[206268671] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"143.955528ms","start":"2026-08-12T11:53:34.734673Z","end":"2026-08-12T11:53:34.878628Z","steps":["trace[206268671] 'process raft request' (duration: 143.138195ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:35.024757Z","caller":"traceutil/trace.go:171","msg":"trace[1191199866] linearizableReadLoop","detail":"{readStateIndex:26333; appliedIndex:26332; }","duration":"139.063449ms","start":"2026-08-12T11:53:34.88566Z","end":"2026-08-12T11:53:35.024723Z","steps":["trace[1191199866] 'read index received' (duration: 119.138164ms)","trace[1191199866] 'applied index is now lower than readState.Index' (duration: 19.923665ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:53:35.025082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.390988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-08-12T11:53:35.024932Z","caller":"traceutil/trace.go:171","msg":"trace[639013009] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"141.1965ms","start":"2026-08-12T11:53:34.883703Z","end":"2026-08-12T11:53:35.024899Z","steps":["trace[639013009] 'process raft request' (duration: 121.226424ms)","trace[639013009] 'compare' (duration: 19.612636ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:35.025138Z","caller":"traceutil/trace.go:171","msg":"trace[852807657] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25503; }","duration":"139.488422ms","start":"2026-08-12T11:53:34.885637Z","end":"2026-08-12T11:53:35.025125Z","steps":["trace[852807657] 'agreement among raft nodes before linearized reading' (duration: 139.253834ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:35.250022Z","caller":"traceutil/trace.go:171","msg":"trace[2002342381] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"218.874611ms","start":"2026-08-12T11:53:35.031115Z","end":"2026-08-12T11:53:35.24999Z","steps":["trace[2002342381] 'process raft request' (duration: 125.936988ms)","trace[2002342381] 'compare' (duration: 92.646485ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:35.250072Z","caller":"traceutil/trace.go:171","msg":"trace[1168624317] linearizableReadLoop","detail":"{readStateIndex:26335; appliedIndex:26333; }","duration":"218.211002ms","start":"2026-08-12T11:53:35.031837Z","end":"2026-08-12T11:53:35.250048Z","steps":["trace[1168624317] 'read index received' (duration: 53.260839ms)","trace[1168624317] 'applied index is now lower than readState.Index' (duration: 164.947673ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:35.25014Z","caller":"traceutil/trace.go:171","msg":"trace[766545224] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"121.321717ms","start":"2026-08-12T11:53:35.128782Z","end":"2026-08-12T11:53:35.250103Z","steps":["trace[766545224] 'process raft request' (duration: 121.201564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:35.250345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.450799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:35.25049Z","caller":"traceutil/trace.go:171","msg":"trace[203989746] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25506; }","duration":"218.667965ms","start":"2026-08-12T11:53:35.031773Z","end":"2026-08-12T11:53:35.250441Z","steps":["trace[203989746] 'agreement among raft nodes before linearized reading' (duration: 218.375817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:35.258863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.380722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:35.25895Z","caller":"traceutil/trace.go:171","msg":"trace[1918112962] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25508; }","duration":"215.513286ms","start":"2026-08-12T11:53:35.043415Z","end":"2026-08-12T11:53:35.258928Z","steps":["trace[1918112962] 'agreement among raft nodes before linearized reading' (duration: 215.377672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:35.25918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.013499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-12T11:53:35.259243Z","caller":"traceutil/trace.go:171","msg":"trace[1473997964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25508; }","duration":"222.101812ms","start":"2026-08-12T11:53:35.037124Z","end":"2026-08-12T11:53:35.259226Z","steps":["trace[1473997964] 'agreement among raft nodes before linearized reading' (duration: 221.883345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:35.25926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.727057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-12T11:53:35.259305Z","caller":"traceutil/trace.go:171","msg":"trace[1697898620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25508; }","duration":"218.774038ms","start":"2026-08-12T11:53:35.040519Z","end":"2026-08-12T11:53:35.259293Z","steps":["trace[1697898620] 'agreement among raft nodes before linearized reading' (duration: 218.540682ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:35.392876Z","caller":"traceutil/trace.go:171","msg":"trace[631321419] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"128.009806ms","start":"2026-08-12T11:53:35.26484Z","end":"2026-08-12T11:53:35.39285Z","steps":["trace[631321419] 'process raft request' (duration: 127.680077ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:35.452318Z","caller":"traceutil/trace.go:171","msg":"trace[583792982] linearizableReadLoop","detail":"{readStateIndex:26341; appliedIndex:26339; }","duration":"129.819398ms","start":"2026-08-12T11:53:35.322458Z","end":"2026-08-12T11:53:35.452278Z","steps":["trace[583792982] 'read index received' (duration: 69.977423ms)","trace[583792982] 'applied index is now lower than readState.Index' (duration: 59.839695ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:53:35.452738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.244179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9545"} {"level":"info","ts":"2026-08-12T11:53:35.452793Z","caller":"traceutil/trace.go:171","msg":"trace[380625436] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25510; }","duration":"130.380223ms","start":"2026-08-12T11:53:35.322397Z","end":"2026-08-12T11:53:35.452777Z","steps":["trace[380625436] 'agreement among raft nodes before linearized reading' (duration: 130.026303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:37.199617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.183403ms","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-08-12T11:53:37.199721Z","caller":"traceutil/trace.go:171","msg":"trace[686746633] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25517; }","duration":"109.345107ms","start":"2026-08-12T11:53:37.090348Z","end":"2026-08-12T11:53:37.199693Z","steps":["trace[686746633] 'range keys from in-memory index tree' (duration: 108.951806ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:37.329Z","caller":"traceutil/trace.go:171","msg":"trace[144318027] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"124.401915ms","start":"2026-08-12T11:53:37.204561Z","end":"2026-08-12T11:53:37.328963Z","steps":["trace[144318027] 'process raft request' (duration: 123.974453ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:37.374317Z","caller":"traceutil/trace.go:171","msg":"trace[1786987061] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"118.497787ms","start":"2026-08-12T11:53:37.255788Z","end":"2026-08-12T11:53:37.374285Z","steps":["trace[1786987061] 'process raft request' (duration: 118.236399ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:37.374625Z","caller":"traceutil/trace.go:171","msg":"trace[1125892460] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"110.251522ms","start":"2026-08-12T11:53:37.264344Z","end":"2026-08-12T11:53:37.374596Z","steps":["trace[1125892460] 'process raft request' (duration: 109.878712ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:37.374715Z","caller":"traceutil/trace.go:171","msg":"trace[488580979] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"109.988225ms","start":"2026-08-12T11:53:37.264712Z","end":"2026-08-12T11:53:37.3747Z","steps":["trace[488580979] 'process raft request' (duration: 109.78413ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:37.59935Z","caller":"traceutil/trace.go:171","msg":"trace[908688287] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"258.518892ms","start":"2026-08-12T11:53:37.340787Z","end":"2026-08-12T11:53:37.599305Z","steps":["trace[908688287] 'process raft request' (duration: 159.042005ms)","trace[908688287] 'compare' (duration: 99.257751ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:37.603277Z","caller":"traceutil/trace.go:171","msg":"trace[37796532] linearizableReadLoop","detail":"{readStateIndex:26354; appliedIndex:26352; }","duration":"205.300065ms","start":"2026-08-12T11:53:37.397935Z","end":"2026-08-12T11:53:37.603235Z","steps":["trace[37796532] 'read index received' (duration: 102.04255ms)","trace[37796532] 'applied index is now lower than readState.Index' (duration: 103.255725ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:53:37.603753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.778799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-12T11:53:37.603861Z","caller":"traceutil/trace.go:171","msg":"trace[1025004150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25523; }","duration":"205.951643ms","start":"2026-08-12T11:53:37.397887Z","end":"2026-08-12T11:53:37.603838Z","steps":["trace[1025004150] 'agreement among raft nodes before linearized reading' (duration: 205.569123ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:37.603357Z","caller":"traceutil/trace.go:171","msg":"trace[390539303] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"224.047797ms","start":"2026-08-12T11:53:37.37927Z","end":"2026-08-12T11:53:37.603318Z","steps":["trace[390539303] 'process raft request' (duration: 223.728688ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:42.987744Z","caller":"traceutil/trace.go:171","msg":"trace[1179339957] transaction","detail":"{read_only:false; response_revision:25560; number_of_response:1; }","duration":"130.118327ms","start":"2026-08-12T11:53:42.85758Z","end":"2026-08-12T11:53:42.987699Z","steps":["trace[1179339957] 'process raft request' (duration: 129.908961ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:43.055877Z","caller":"traceutil/trace.go:171","msg":"trace[846680507] linearizableReadLoop","detail":"{readStateIndex:26393; appliedIndex:26391; }","duration":"106.857827ms","start":"2026-08-12T11:53:42.948987Z","end":"2026-08-12T11:53:43.055845Z","steps":["trace[846680507] 'read index received' (duration: 38.579143ms)","trace[846680507] 'applied index is now lower than readState.Index' (duration: 68.277274ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:43.056003Z","caller":"traceutil/trace.go:171","msg":"trace[70310038] transaction","detail":"{read_only:false; response_revision:25561; number_of_response:1; }","duration":"142.59969ms","start":"2026-08-12T11:53:42.91336Z","end":"2026-08-12T11:53:43.055959Z","steps":["trace[70310038] 'process raft request' (duration: 142.295072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:43.056055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.020431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:53:43.056273Z","caller":"traceutil/trace.go:171","msg":"trace[60244310] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25561; }","duration":"107.244278ms","start":"2026-08-12T11:53:42.948968Z","end":"2026-08-12T11:53:43.056212Z","steps":["trace[60244310] 'agreement among raft nodes before linearized reading' (duration: 106.993591ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:43.10736Z","caller":"traceutil/trace.go:171","msg":"trace[2078661877] transaction","detail":"{read_only:false; response_revision:25562; number_of_response:1; }","duration":"105.786807ms","start":"2026-08-12T11:53:43.001528Z","end":"2026-08-12T11:53:43.107315Z","steps":["trace[2078661877] 'process raft request' (duration: 97.366128ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:43.495079Z","caller":"traceutil/trace.go:171","msg":"trace[349781202] transaction","detail":"{read_only:false; response_revision:25565; number_of_response:1; }","duration":"101.186107ms","start":"2026-08-12T11:53:43.393866Z","end":"2026-08-12T11:53:43.495052Z","steps":["trace[349781202] 'process raft request' (duration: 101.051873ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:45.235127Z","caller":"traceutil/trace.go:171","msg":"trace[1624598754] linearizableReadLoop","detail":"{readStateIndex:26407; appliedIndex:26406; }","duration":"117.18512ms","start":"2026-08-12T11:53:45.117912Z","end":"2026-08-12T11:53:45.235097Z","steps":["trace[1624598754] 'read index received' (duration: 106.98728ms)","trace[1624598754] 'applied index is now lower than readState.Index' (duration: 10.19667ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:45.235163Z","caller":"traceutil/trace.go:171","msg":"trace[1010432994] transaction","detail":"{read_only:false; response_revision:25575; number_of_response:1; }","duration":"121.187283ms","start":"2026-08-12T11:53:45.113936Z","end":"2026-08-12T11:53:45.235124Z","steps":["trace[1010432994] 'process raft request' (duration: 111.016634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:45.235408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.477138ms","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-08-12T11:53:45.235507Z","caller":"traceutil/trace.go:171","msg":"trace[892447993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25575; }","duration":"117.622312ms","start":"2026-08-12T11:53:45.117862Z","end":"2026-08-12T11:53:45.235484Z","steps":["trace[892447993] 'agreement among raft nodes before linearized reading' (duration: 117.341204ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:47.006598Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22141} {"level":"info","ts":"2026-08-12T11:53:47.118892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22141,"took":"107.26565ms","hash":2884621865,"current-db-size-bytes":58961920,"current-db-size":"59 MB","current-db-size-in-use-bytes":44302336,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-12T11:53:47.119039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2884621865,"revision":22141,"compact-revision":19855} {"level":"info","ts":"2026-08-12T11:53:49.511016Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006754.wal"} {"level":"info","ts":"2026-08-12T11:53:53.659298Z","caller":"traceutil/trace.go:171","msg":"trace[1747650289] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"128.987014ms","start":"2026-08-12T11:53:53.53028Z","end":"2026-08-12T11:53:53.659267Z","steps":["trace[1747650289] 'process raft request' (duration: 128.753838ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:53.826267Z","caller":"traceutil/trace.go:171","msg":"trace[391580095] linearizableReadLoop","detail":"{readStateIndex:26485; appliedIndex:26482; }","duration":"269.68593ms","start":"2026-08-12T11:53:53.556558Z","end":"2026-08-12T11:53:53.826244Z","steps":["trace[391580095] 'read index received' (duration: 102.607116ms)","trace[391580095] 'applied index is now lower than readState.Index' (duration: 167.077474ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:53.826366Z","caller":"traceutil/trace.go:171","msg":"trace[461312379] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"295.719438ms","start":"2026-08-12T11:53:53.53056Z","end":"2026-08-12T11:53:53.826279Z","steps":["trace[461312379] 'process raft request' (duration: 231.591531ms)","trace[461312379] 'compare' (duration: 63.725966ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:53.826529Z","caller":"traceutil/trace.go:171","msg":"trace[268986805] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"277.43773ms","start":"2026-08-12T11:53:53.549049Z","end":"2026-08-12T11:53:53.826487Z","steps":["trace[268986805] 'process raft request' (duration: 277.11053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:53.826565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.68604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-12T11:53:53.826572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.999118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-12T11:53:53.826629Z","caller":"traceutil/trace.go:171","msg":"trace[1104103895] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25650; }","duration":"270.122342ms","start":"2026-08-12T11:53:53.556491Z","end":"2026-08-12T11:53:53.826613Z","steps":["trace[1104103895] 'agreement among raft nodes before linearized reading' (duration: 269.911026ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:53.826617Z","caller":"traceutil/trace.go:171","msg":"trace[1348521036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25650; }","duration":"149.809733ms","start":"2026-08-12T11:53:53.676795Z","end":"2026-08-12T11:53:53.826605Z","steps":["trace[1348521036] 'agreement among raft nodes before linearized reading' (duration: 149.642538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:53.826721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.184848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-12T11:53:53.826752Z","caller":"traceutil/trace.go:171","msg":"trace[1446697800] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25650; }","duration":"142.272681ms","start":"2026-08-12T11:53:53.684471Z","end":"2026-08-12T11:53:53.826744Z","steps":["trace[1446697800] 'agreement among raft nodes before linearized reading' (duration: 142.188438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:53.826722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.948755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T11:53:53.826835Z","caller":"traceutil/trace.go:171","msg":"trace[1904217325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25650; }","duration":"134.14177ms","start":"2026-08-12T11:53:53.692677Z","end":"2026-08-12T11:53:53.826819Z","steps":["trace[1904217325] 'agreement among raft nodes before linearized reading' (duration: 133.858062ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:54.026101Z","caller":"traceutil/trace.go:171","msg":"trace[570492238] transaction","detail":"{read_only:false; response_revision:25652; number_of_response:1; }","duration":"193.850351ms","start":"2026-08-12T11:53:53.832215Z","end":"2026-08-12T11:53:54.026065Z","steps":["trace[570492238] 'process raft request' (duration: 154.674151ms)","trace[570492238] 'compare' (duration: 38.930653ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:54.026589Z","caller":"traceutil/trace.go:171","msg":"trace[201750894] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"194.276963ms","start":"2026-08-12T11:53:53.83228Z","end":"2026-08-12T11:53:54.026557Z","steps":["trace[201750894] 'process raft request' (duration: 193.718867ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:53:54.026822Z","caller":"traceutil/trace.go:171","msg":"trace[1232034447] linearizableReadLoop","detail":"{readStateIndex:26488; appliedIndex:26485; }","duration":"194.240992ms","start":"2026-08-12T11:53:53.832539Z","end":"2026-08-12T11:53:54.02678Z","steps":["trace[1232034447] 'read index received' (duration: 61.215064ms)","trace[1232034447] 'applied index is now lower than readState.Index' (duration: 133.023818ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:53:54.026891Z","caller":"traceutil/trace.go:171","msg":"trace[1008569138] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"193.971645ms","start":"2026-08-12T11:53:53.83287Z","end":"2026-08-12T11:53:54.026842Z","steps":["trace[1008569138] 'process raft request' (duration: 193.566844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:54.027059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.49436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18230"} {"level":"info","ts":"2026-08-12T11:53:54.027134Z","caller":"traceutil/trace.go:171","msg":"trace[1360922345] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25654; }","duration":"194.602724ms","start":"2026-08-12T11:53:53.832515Z","end":"2026-08-12T11:53:54.027117Z","steps":["trace[1360922345] 'agreement among raft nodes before linearized reading' (duration: 194.390967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:54.096903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.937935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:53:54.097037Z","caller":"traceutil/trace.go:171","msg":"trace[741272752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25655; }","duration":"241.114042ms","start":"2026-08-12T11:53:53.855899Z","end":"2026-08-12T11:53:54.097013Z","steps":["trace[741272752] 'agreement among raft nodes before linearized reading' (duration: 240.890414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:53:54.097209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.590486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-12T11:53:54.097312Z","caller":"traceutil/trace.go:171","msg":"trace[496908605] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25655; }","duration":"144.771811ms","start":"2026-08-12T11:53:53.952506Z","end":"2026-08-12T11:53:54.097278Z","steps":["trace[496908605] 'agreement among raft nodes before linearized reading' (duration: 144.472983ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:00.330035Z","caller":"traceutil/trace.go:171","msg":"trace[541215125] transaction","detail":"{read_only:false; response_revision:26311; number_of_response:1; }","duration":"182.012166ms","start":"2026-08-12T11:55:00.147981Z","end":"2026-08-12T11:55:00.329993Z","steps":["trace[541215125] 'process raft request' (duration: 171.557541ms)","trace[541215125] 'compare' (duration: 10.212258ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:00.346188Z","caller":"traceutil/trace.go:171","msg":"trace[2010780722] transaction","detail":"{read_only:false; response_revision:26312; number_of_response:1; }","duration":"135.871159ms","start":"2026-08-12T11:55:00.210299Z","end":"2026-08-12T11:55:00.34617Z","steps":["trace[2010780722] 'process raft request' (duration: 135.56749ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:00.346254Z","caller":"traceutil/trace.go:171","msg":"trace[1977492113] linearizableReadLoop","detail":"{readStateIndex:27164; appliedIndex:27161; }","duration":"135.779556ms","start":"2026-08-12T11:55:00.210453Z","end":"2026-08-12T11:55:00.346233Z","steps":["trace[1977492113] 'read index received' (duration: 109.015079ms)","trace[1977492113] 'applied index is now lower than readState.Index' (duration: 26.763627ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:00.346511Z","caller":"traceutil/trace.go:171","msg":"trace[1570662419] transaction","detail":"{read_only:false; response_revision:26313; number_of_response:1; }","duration":"136.092505ms","start":"2026-08-12T11:55:00.210406Z","end":"2026-08-12T11:55:00.346499Z","steps":["trace[1570662419] 'process raft request' (duration: 135.586891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:00.347985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.509075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:55:00.34805Z","caller":"traceutil/trace.go:171","msg":"trace[999501565] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:26313; }","duration":"137.638439ms","start":"2026-08-12T11:55:00.210394Z","end":"2026-08-12T11:55:00.348032Z","steps":["trace[999501565] 'agreement among raft nodes before linearized reading' (duration: 136.127245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:00.355749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.209417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-12T11:55:00.355789Z","caller":"traceutil/trace.go:171","msg":"trace[92653850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26314; }","duration":"125.263769ms","start":"2026-08-12T11:55:00.230516Z","end":"2026-08-12T11:55:00.355779Z","steps":["trace[92653850] 'agreement among raft nodes before linearized reading' (duration: 125.173136ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:42.067635Z","caller":"traceutil/trace.go:171","msg":"trace[2005913311] transaction","detail":"{read_only:false; response_revision:26748; number_of_response:1; }","duration":"109.516673ms","start":"2026-08-12T11:55:41.958072Z","end":"2026-08-12T11:55:42.067589Z","steps":["trace[2005913311] 'process raft request' (duration: 67.122062ms)","trace[2005913311] 'compare' (duration: 42.231696ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:57:06.774616Z","caller":"traceutil/trace.go:171","msg":"trace[1474525036] transaction","detail":"{read_only:false; response_revision:27595; number_of_response:1; }","duration":"120.926056ms","start":"2026-08-12T11:57:06.653649Z","end":"2026-08-12T11:57:06.774575Z","steps":["trace[1474525036] 'process raft request' (duration: 120.635878ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:58:47.012567Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25589} {"level":"info","ts":"2026-08-12T11:58:47.175087Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25589,"took":"157.614094ms","hash":3133290500,"current-db-size-bytes":61566976,"current-db-size":"62 MB","current-db-size-in-use-bytes":36368384,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-12T11:58:47.175151Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3133290500,"revision":25589,"compact-revision":22141} {"level":"info","ts":"2026-08-12T12:00:02.420569Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2ba8e3c2b0588654","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T12:00:02.426527Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-12T12:00:02.426813Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-12T12:03:47.021349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28465} {"level":"info","ts":"2026-08-12T12:03:47.167395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28465,"took":"139.4634ms","hash":1325258774,"current-db-size-bytes":61566976,"current-db-size":"62 MB","current-db-size-in-use-bytes":32223232,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-12T12:03:47.167481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1325258774,"revision":28465,"compact-revision":25589} {"level":"info","ts":"2026-08-12T12:08:47.029791Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30786} {"level":"info","ts":"2026-08-12T12:08:47.169485Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30786,"took":"135.181729ms","hash":2339249927,"current-db-size-bytes":61566976,"current-db-size":"62 MB","current-db-size-in-use-bytes":30642176,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T12:08:47.169578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2339249927,"revision":30786,"compact-revision":28465}