{"level":"warn","ts":"2026-06-30T18:03:36.500916Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-30T18:03:36.501013Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-06-30T18:03:36.501083Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-30T18:03:36.501098Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-06-30T18:03:36.501116Z","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-06-30T18:03:36.501789Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-06-30T18:03:36.501937Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-06-30T18:03:36.505966Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.762286ms"} {"level":"info","ts":"2026-06-30T18:03:36.513343Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-06-30T18:03:36.51342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-06-30T18:03:36.513443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-06-30T18:03:36.513456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-30T18:03:36.513464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-06-30T18:03:36.513502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-06-30T18:03:36.51836Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-30T18:03:36.520254Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-30T18:03:36.52176Z","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-06-30T18:03:36.5268Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-30T18:03:36.527959Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-30T18:03:36.528139Z","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-06-30T18:03:36.528219Z","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-06-30T18:03:36.528236Z","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-06-30T18:03:36.528666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-06-30T18:03:36.528838Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-06-30T18:03:36.533068Z","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-06-30T18:03:36.533418Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-30T18:03:36.533472Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-30T18:03:36.533636Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-06-30T18:03:36.533657Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-06-30T18:03:37.513745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-06-30T18:03:37.513841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-30T18:03:37.513914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-06-30T18:03:37.513945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-06-30T18:03:37.513964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-06-30T18:03:37.513983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-06-30T18:03:37.514006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-06-30T18:03:37.516038Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-06-30T18:03:37.516034Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T18:03:37.51606Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T18:03:37.516043Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T18:03:37.51642Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-30T18:03:37.516487Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-30T18:03:37.518393Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-30T18:03:37.521326Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T18:03:37.521524Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T18:03:37.521571Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T18:03:37.522062Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-06-30T18:03:52.707214Z","caller":"traceutil/trace.go:171","msg":"trace[286397793] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"273.89381ms","start":"2026-06-30T18:03:52.433301Z","end":"2026-06-30T18:03:52.707195Z","steps":["trace[286397793] 'process raft request' (duration: 273.790338ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:04:21.268657Z","caller":"traceutil/trace.go:171","msg":"trace[1831209687] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"187.177489ms","start":"2026-06-30T18:04:21.081451Z","end":"2026-06-30T18:04:21.268629Z","steps":["trace[1831209687] 'process raft request' (duration: 184.649278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:04:21.841801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.608042ms","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-06-30T18:04:21.841918Z","caller":"traceutil/trace.go:171","msg":"trace[1276330365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"154.719594ms","start":"2026-06-30T18:04:21.68718Z","end":"2026-06-30T18:04:21.8419Z","steps":["trace[1276330365] 'range keys from in-memory index tree' (duration: 154.489259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:04:21.841777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.339645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-30T18:04:21.842071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.36489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-30T18:04:21.842157Z","caller":"traceutil/trace.go:171","msg":"trace[593972025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"305.750363ms","start":"2026-06-30T18:04:21.536365Z","end":"2026-06-30T18:04:21.842115Z","steps":["trace[593972025] 'range keys from in-memory index tree' (duration: 305.201601ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:04:21.842161Z","caller":"traceutil/trace.go:171","msg":"trace[1807930193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"115.529543ms","start":"2026-06-30T18:04:21.726607Z","end":"2026-06-30T18:04:21.842137Z","steps":["trace[1807930193] 'range keys from in-memory index tree' (duration: 115.112663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:04:21.842247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:04:21.536348Z","time spent":"305.859135ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T18:04:21.842684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.151203ms","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-06-30T18:04:21.842807Z","caller":"traceutil/trace.go:171","msg":"trace[1211863889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"107.319477ms","start":"2026-06-30T18:04:21.735459Z","end":"2026-06-30T18:04:21.842778Z","steps":["trace[1211863889] 'range keys from in-memory index tree' (duration: 106.230144ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:04:23.005958Z","caller":"traceutil/trace.go:171","msg":"trace[1159310979] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"103.713564ms","start":"2026-06-30T18:04:22.90222Z","end":"2026-06-30T18:04:23.005933Z","steps":["trace[1159310979] 'read index received' (duration: 99.297095ms)","trace[1159310979] 'applied index is now lower than readState.Index' (duration: 4.415409ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:04:23.006158Z","caller":"traceutil/trace.go:171","msg":"trace[994816460] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"164.761166ms","start":"2026-06-30T18:04:22.84137Z","end":"2026-06-30T18:04:23.006131Z","steps":["trace[994816460] 'process raft request' (duration: 160.230374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:04:23.006288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.00863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:04:23.006362Z","caller":"traceutil/trace.go:171","msg":"trace[1305365425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"104.176513ms","start":"2026-06-30T18:04:22.902168Z","end":"2026-06-30T18:04:23.006345Z","steps":["trace[1305365425] 'agreement among raft nodes before linearized reading' (duration: 103.940379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:04:37.323299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.070985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-30T18:04:37.323435Z","caller":"traceutil/trace.go:171","msg":"trace[1341930639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:759; }","duration":"222.280138ms","start":"2026-06-30T18:04:37.101131Z","end":"2026-06-30T18:04:37.323411Z","steps":["trace[1341930639] 'range keys from in-memory index tree' (duration: 221.787829ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:04:50.12159Z","caller":"traceutil/trace.go:171","msg":"trace[1259852661] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"102.516189ms","start":"2026-06-30T18:04:50.019037Z","end":"2026-06-30T18:04:50.121553Z","steps":["trace[1259852661] 'process raft request' (duration: 36.094839ms)","trace[1259852661] 'compare' (duration: 66.191355ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:05:51.733444Z","caller":"traceutil/trace.go:171","msg":"trace[1893709801] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"146.562883ms","start":"2026-06-30T18:05:51.586854Z","end":"2026-06-30T18:05:51.733417Z","steps":["trace[1893709801] 'process raft request' (duration: 146.41786ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:05:52.463529Z","caller":"traceutil/trace.go:171","msg":"trace[1500912009] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1887; }","duration":"126.927279ms","start":"2026-06-30T18:05:52.336572Z","end":"2026-06-30T18:05:52.4635Z","steps":["trace[1500912009] 'read index received' (duration: 126.624142ms)","trace[1500912009] 'applied index is now lower than readState.Index' (duration: 302.167µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:05:52.464056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.542997ms","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-06-30T18:05:52.464148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.52112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-30T18:05:52.464273Z","caller":"traceutil/trace.go:171","msg":"trace[739112210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1821; }","duration":"127.737754ms","start":"2026-06-30T18:05:52.336505Z","end":"2026-06-30T18:05:52.464243Z","steps":["trace[739112210] 'agreement among raft nodes before linearized reading' (duration: 127.226764ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:05:52.464208Z","caller":"traceutil/trace.go:171","msg":"trace[1653391267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1821; }","duration":"111.75534ms","start":"2026-06-30T18:05:52.352433Z","end":"2026-06-30T18:05:52.464189Z","steps":["trace[1653391267] 'agreement among raft nodes before linearized reading' (duration: 111.333612ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:05:52.464285Z","caller":"traceutil/trace.go:171","msg":"trace[824092374] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"225.927119ms","start":"2026-06-30T18:05:52.238299Z","end":"2026-06-30T18:05:52.464226Z","steps":["trace[824092374] 'process raft request' (duration: 225.025201ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:05:52.579743Z","caller":"traceutil/trace.go:171","msg":"trace[660947169] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"110.043216ms","start":"2026-06-30T18:05:52.46967Z","end":"2026-06-30T18:05:52.579713Z","steps":["trace[660947169] 'process raft request' (duration: 109.874173ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:05:52.580036Z","caller":"traceutil/trace.go:171","msg":"trace[1507482691] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"110.186759ms","start":"2026-06-30T18:05:52.469829Z","end":"2026-06-30T18:05:52.580016Z","steps":["trace[1507482691] 'process raft request' (duration: 109.836282ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:05:52.580149Z","caller":"traceutil/trace.go:171","msg":"trace[1116512293] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"109.21843ms","start":"2026-06-30T18:05:52.47092Z","end":"2026-06-30T18:05:52.580138Z","steps":["trace[1116512293] 'process raft request' (duration: 109.035477ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:05:52.58018Z","caller":"traceutil/trace.go:171","msg":"trace[354661255] linearizableReadLoop","detail":"{readStateIndex:1892; appliedIndex:1888; }","duration":"105.962127ms","start":"2026-06-30T18:05:52.474188Z","end":"2026-06-30T18:05:52.58015Z","steps":["trace[354661255] 'read index received' (duration: 30.365075ms)","trace[354661255] 'applied index is now lower than readState.Index' (duration: 75.595501ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:05:52.58021Z","caller":"traceutil/trace.go:171","msg":"trace[1607653822] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"103.860285ms","start":"2026-06-30T18:05:52.476324Z","end":"2026-06-30T18:05:52.580184Z","steps":["trace[1607653822] 'process raft request' (duration: 103.757773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:05:52.580453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.234213ms","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-06-30T18:05:52.580535Z","caller":"traceutil/trace.go:171","msg":"trace[1448497675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1826; }","duration":"106.357395ms","start":"2026-06-30T18:05:52.474156Z","end":"2026-06-30T18:05:52.580513Z","steps":["trace[1448497675] 'agreement among raft nodes before linearized reading' (duration: 106.11218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:05:52.583972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.404805ms","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-06-30T18:05:52.584039Z","caller":"traceutil/trace.go:171","msg":"trace[1892617523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1827; }","duration":"108.515517ms","start":"2026-06-30T18:05:52.475507Z","end":"2026-06-30T18:05:52.584022Z","steps":["trace[1892617523] 'agreement among raft nodes before linearized reading' (duration: 108.275922ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:08:04.587467Z","caller":"traceutil/trace.go:171","msg":"trace[104809489] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"115.819209ms","start":"2026-06-30T18:08:04.471623Z","end":"2026-06-30T18:08:04.587442Z","steps":["trace[104809489] 'process raft request' (duration: 115.658076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:08:04.847649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.651004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-30T18:08:04.84774Z","caller":"traceutil/trace.go:171","msg":"trace[667871905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2782; }","duration":"214.753806ms","start":"2026-06-30T18:08:04.632971Z","end":"2026-06-30T18:08:04.847725Z","steps":["trace[667871905] 'range keys from in-memory index tree' (duration: 214.502561ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:12:07.367622Z","caller":"traceutil/trace.go:171","msg":"trace[1136898602] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"118.057444ms","start":"2026-06-30T18:12:07.249527Z","end":"2026-06-30T18:12:07.367585Z","steps":["trace[1136898602] 'process raft request' (duration: 71.590064ms)","trace[1136898602] 'compare' (duration: 46.242416ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:12:10.560894Z","caller":"traceutil/trace.go:171","msg":"trace[301288369] linearizableReadLoop","detail":"{readStateIndex:5372; appliedIndex:5371; }","duration":"133.82507ms","start":"2026-06-30T18:12:10.427045Z","end":"2026-06-30T18:12:10.56087Z","steps":["trace[301288369] 'read index received' (duration: 133.656707ms)","trace[301288369] 'applied index is now lower than readState.Index' (duration: 167.343µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:12:10.561165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.082605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:12:10.561255Z","caller":"traceutil/trace.go:171","msg":"trace[1007344299] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:5184; }","duration":"134.226537ms","start":"2026-06-30T18:12:10.427005Z","end":"2026-06-30T18:12:10.561232Z","steps":["trace[1007344299] 'agreement among raft nodes before linearized reading' (duration: 133.978972ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:12:10.560961Z","caller":"traceutil/trace.go:171","msg":"trace[1921061909] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"138.868638ms","start":"2026-06-30T18:12:10.422051Z","end":"2026-06-30T18:12:10.560919Z","steps":["trace[1921061909] 'process raft request' (duration: 138.663144ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:12:19.590224Z","caller":"traceutil/trace.go:171","msg":"trace[1521424478] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"182.362699ms","start":"2026-06-30T18:12:19.40782Z","end":"2026-06-30T18:12:19.590182Z","steps":["trace[1521424478] 'process raft request' (duration: 98.764601ms)","trace[1521424478] 'compare' (duration: 83.054687ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:12:21.658507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.94054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T18:12:21.658584Z","caller":"traceutil/trace.go:171","msg":"trace[1206005701] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5382; }","duration":"136.062643ms","start":"2026-06-30T18:12:21.522506Z","end":"2026-06-30T18:12:21.658568Z","steps":["trace[1206005701] 'count revisions from in-memory index tree' (duration: 135.845468ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:13:37.54325Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2967} {"level":"info","ts":"2026-06-30T18:13:37.613413Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2967,"took":"68.789296ms","hash":1975867523,"current-db-size-bytes":57192448,"current-db-size":"57 MB","current-db-size-in-use-bytes":34037760,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-30T18:13:37.61349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1975867523,"revision":2967,"compact-revision":-1} {"level":"info","ts":"2026-06-30T18:18:19.374537Z","caller":"traceutil/trace.go:171","msg":"trace[44807338] transaction","detail":"{read_only:false; response_revision:8166; number_of_response:1; }","duration":"319.600145ms","start":"2026-06-30T18:18:19.054915Z","end":"2026-06-30T18:18:19.374515Z","steps":["trace[44807338] 'process raft request' (duration: 319.455252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:18:19.375139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:18:19.054894Z","time spent":"319.733617ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:18:30.989469Z","caller":"traceutil/trace.go:171","msg":"trace[1897143933] transaction","detail":"{read_only:false; response_revision:8226; number_of_response:1; }","duration":"340.991271ms","start":"2026-06-30T18:18:30.648444Z","end":"2026-06-30T18:18:30.989435Z","steps":["trace[1897143933] 'process raft request' (duration: 340.90296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:18:30.989665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:18:30.648418Z","time spent":"341.153264ms","remote":"127.0.0.1:49236","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-06-30T18:18:30.989485Z","caller":"traceutil/trace.go:171","msg":"trace[1279826378] transaction","detail":"{read_only:false; response_revision:8225; number_of_response:1; }","duration":"341.799726ms","start":"2026-06-30T18:18:30.647646Z","end":"2026-06-30T18:18:30.989446Z","steps":["trace[1279826378] 'process raft request' (duration: 251.501493ms)","trace[1279826378] 'compare' (duration: 90.036148ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:18:30.989734Z","caller":"traceutil/trace.go:171","msg":"trace[352190385] transaction","detail":"{read_only:false; response_revision:8227; number_of_response:1; }","duration":"340.709146ms","start":"2026-06-30T18:18:30.649014Z","end":"2026-06-30T18:18:30.989723Z","steps":["trace[352190385] 'process raft request' (duration: 340.379829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:18:30.989835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:18:30.648997Z","time spent":"340.772586ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:18:30.989835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:18:30.647628Z","time spent":"342.121362ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:18:30.989908Z","caller":"traceutil/trace.go:171","msg":"trace[1402688029] linearizableReadLoop","detail":"{readStateIndex:8509; appliedIndex:8506; }","duration":"303.205543ms","start":"2026-06-30T18:18:30.68662Z","end":"2026-06-30T18:18:30.989826Z","steps":["trace[1402688029] 'read index received' (duration: 212.623695ms)","trace[1402688029] 'applied index is now lower than readState.Index' (duration: 90.574288ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:18:30.990023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.375286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-30T18:18:30.990157Z","caller":"traceutil/trace.go:171","msg":"trace[1373554525] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:8227; }","duration":"303.523599ms","start":"2026-06-30T18:18:30.686593Z","end":"2026-06-30T18:18:30.990117Z","steps":["trace[1373554525] 'agreement among raft nodes before linearized reading' (duration: 303.298885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:18:30.990219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:18:30.686582Z","time spent":"303.627102ms","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-06-30T18:18:30.990277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.804037ms","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-06-30T18:18:30.990328Z","caller":"traceutil/trace.go:171","msg":"trace[2134729852] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8227; }","duration":"262.896558ms","start":"2026-06-30T18:18:30.727418Z","end":"2026-06-30T18:18:30.990315Z","steps":["trace[2134729852] 'agreement among raft nodes before linearized reading' (duration: 262.735976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:18:30.990757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.715993ms","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-06-30T18:18:30.990857Z","caller":"traceutil/trace.go:171","msg":"trace[2043078895] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8227; }","duration":"273.865596ms","start":"2026-06-30T18:18:30.716976Z","end":"2026-06-30T18:18:30.990841Z","steps":["trace[2043078895] 'agreement among raft nodes before linearized reading' (duration: 273.207604ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:18:31.76422Z","caller":"traceutil/trace.go:171","msg":"trace[172335672] linearizableReadLoop","detail":"{readStateIndex:8515; appliedIndex:8513; }","duration":"304.467207ms","start":"2026-06-30T18:18:31.459711Z","end":"2026-06-30T18:18:31.764178Z","steps":["trace[172335672] 'read index received' (duration: 47.44924ms)","trace[172335672] 'applied index is now lower than readState.Index' (duration: 257.016707ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:18:31.76445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.717003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T18:18:31.764494Z","caller":"traceutil/trace.go:171","msg":"trace[2137736124] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:8232; }","duration":"304.818874ms","start":"2026-06-30T18:18:31.459663Z","end":"2026-06-30T18:18:31.764482Z","steps":["trace[2137736124] 'agreement among raft nodes before linearized reading' (duration: 304.64161ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:18:31.764531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:18:31.459645Z","time spent":"304.875726ms","remote":"127.0.0.1:47912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":36,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:18:31.764687Z","caller":"traceutil/trace.go:171","msg":"trace[1011432913] transaction","detail":"{read_only:false; response_revision:8232; number_of_response:1; }","duration":"328.27055ms","start":"2026-06-30T18:18:31.436365Z","end":"2026-06-30T18:18:31.764636Z","steps":["trace[1011432913] 'process raft request' (duration: 296.435626ms)","trace[1011432913] 'compare' (duration: 30.781934ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:18:31.764869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:18:31.436339Z","time spent":"328.436864ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:18:32.002491Z","caller":"traceutil/trace.go:171","msg":"trace[289810444] linearizableReadLoop","detail":"{readStateIndex:8517; appliedIndex:8516; }","duration":"101.381704ms","start":"2026-06-30T18:18:31.90108Z","end":"2026-06-30T18:18:32.002462Z","steps":["trace[289810444] 'read index received' (duration: 101.140969ms)","trace[289810444] 'applied index is now lower than readState.Index' (duration: 239.215µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:18:32.002544Z","caller":"traceutil/trace.go:171","msg":"trace[2083751008] transaction","detail":"{read_only:false; response_revision:8234; number_of_response:1; }","duration":"102.172518ms","start":"2026-06-30T18:18:31.900336Z","end":"2026-06-30T18:18:32.002508Z","steps":["trace[2083751008] 'process raft request' (duration: 101.901693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:18:32.002666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.550968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:18:32.002766Z","caller":"traceutil/trace.go:171","msg":"trace[355034744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8234; }","duration":"101.697431ms","start":"2026-06-30T18:18:31.90105Z","end":"2026-06-30T18:18:32.002747Z","steps":["trace[355034744] 'agreement among raft nodes before linearized reading' (duration: 101.532608ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:18:37.550486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6071} {"level":"info","ts":"2026-06-30T18:18:37.649262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6071,"took":"95.731206ms","hash":4290789946,"current-db-size-bytes":57192448,"current-db-size":"57 MB","current-db-size-in-use-bytes":23298048,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-06-30T18:18:37.64934Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4290789946,"revision":6071,"compact-revision":2967} {"level":"info","ts":"2026-06-30T18:18:59.466808Z","caller":"traceutil/trace.go:171","msg":"trace[490798623] linearizableReadLoop","detail":"{readStateIndex:8721; appliedIndex:8720; }","duration":"327.144437ms","start":"2026-06-30T18:18:59.139631Z","end":"2026-06-30T18:18:59.466776Z","steps":["trace[490798623] 'read index received' (duration: 326.955664ms)","trace[490798623] 'applied index is now lower than readState.Index' (duration: 187.633µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:18:59.467005Z","caller":"traceutil/trace.go:171","msg":"trace[372321563] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"329.335889ms","start":"2026-06-30T18:18:59.137623Z","end":"2026-06-30T18:18:59.466959Z","steps":["trace[372321563] 'process raft request' (duration: 328.965932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:18:59.467164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:18:59.137604Z","time spent":"329.482613ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:18:59.568868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.579581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-30T18:18:59.568958Z","caller":"traceutil/trace.go:171","msg":"trace[1295985301] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8430; }","duration":"156.772466ms","start":"2026-06-30T18:18:59.412164Z","end":"2026-06-30T18:18:59.568936Z","steps":["trace[1295985301] 'agreement among raft nodes before linearized reading' (duration: 54.837281ms)","trace[1295985301] 'range keys from in-memory index tree' (duration: 101.68767ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:18:59.56902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.163843ms","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-06-30T18:18:59.569118Z","caller":"traceutil/trace.go:171","msg":"trace[340680654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8430; }","duration":"429.312126ms","start":"2026-06-30T18:18:59.139776Z","end":"2026-06-30T18:18:59.569088Z","steps":["trace[340680654] 'agreement among raft nodes before linearized reading' (duration: 327.226459ms)","trace[340680654] 'range keys from in-memory index tree' (duration: 101.859393ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:18:59.569169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:18:59.13975Z","time spent":"429.408769ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T18:18:59.56924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.752778ms","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":"warn","ts":"2026-06-30T18:18:59.569272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.634673ms","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-06-30T18:18:59.569314Z","caller":"traceutil/trace.go:171","msg":"trace[99227531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8430; }","duration":"429.674213ms","start":"2026-06-30T18:18:59.139628Z","end":"2026-06-30T18:18:59.569302Z","steps":["trace[99227531] 'agreement among raft nodes before linearized reading' (duration: 327.340361ms)","trace[99227531] 'range keys from in-memory index tree' (duration: 102.225211ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:18:59.569357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:18:59.139597Z","time spent":"429.751775ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-30T18:18:59.569314Z","caller":"traceutil/trace.go:171","msg":"trace[2125429670] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8430; }","duration":"417.88031ms","start":"2026-06-30T18:18:59.151416Z","end":"2026-06-30T18:18:59.569296Z","steps":["trace[2125429670] 'agreement among raft nodes before linearized reading' (duration: 315.589149ms)","trace[2125429670] 'range keys from in-memory index tree' (duration: 102.130749ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:18:59.569442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:18:59.151388Z","time spent":"418.046333ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-30T18:18:59.66044Z","caller":"traceutil/trace.go:171","msg":"trace[889648443] linearizableReadLoop","detail":"{readStateIndex:8722; appliedIndex:8721; }","duration":"123.703878ms","start":"2026-06-30T18:18:59.536687Z","end":"2026-06-30T18:18:59.660391Z","steps":["trace[889648443] 'read index received' (duration: 123.599255ms)","trace[889648443] 'applied index is now lower than readState.Index' (duration: 103.643µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:18:59.660585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.871121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:18:59.660533Z","caller":"traceutil/trace.go:171","msg":"trace[1263833267] transaction","detail":"{read_only:false; response_revision:8431; number_of_response:1; }","duration":"128.02138ms","start":"2026-06-30T18:18:59.532463Z","end":"2026-06-30T18:18:59.660484Z","steps":["trace[1263833267] 'process raft request' (duration: 127.767704ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:18:59.660629Z","caller":"traceutil/trace.go:171","msg":"trace[850521382] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8431; }","duration":"123.977483ms","start":"2026-06-30T18:18:59.536637Z","end":"2026-06-30T18:18:59.660614Z","steps":["trace[850521382] 'agreement among raft nodes before linearized reading' (duration: 123.882882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:19:02.216236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.248753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lwq46\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-30T18:19:02.216347Z","caller":"traceutil/trace.go:171","msg":"trace[600915946] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lwq46; range_end:; response_count:1; response_revision:8449; }","duration":"148.441227ms","start":"2026-06-30T18:19:02.067882Z","end":"2026-06-30T18:19:02.216323Z","steps":["trace[600915946] 'range keys from in-memory index tree' (duration: 148.03739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:19:11.999901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.125737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-c5bwd\" ","response":"range_response_count:1 size:2804"} {"level":"info","ts":"2026-06-30T18:19:12.000041Z","caller":"traceutil/trace.go:171","msg":"trace[1835882832] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-c5bwd; range_end:; response_count:1; response_revision:8541; }","duration":"102.302541ms","start":"2026-06-30T18:19:11.897709Z","end":"2026-06-30T18:19:12.000012Z","steps":["trace[1835882832] 'agreement among raft nodes before linearized reading' (duration: 102.006315ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:19:12.981975Z","caller":"traceutil/trace.go:171","msg":"trace[879277363] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"118.295575ms","start":"2026-06-30T18:19:12.86365Z","end":"2026-06-30T18:19:12.981946Z","steps":["trace[879277363] 'process raft request' (duration: 118.087411ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:19:13.21145Z","caller":"traceutil/trace.go:171","msg":"trace[1782961643] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"332.342347ms","start":"2026-06-30T18:19:12.87908Z","end":"2026-06-30T18:19:13.211422Z","steps":["trace[1782961643] 'process raft request' (duration: 289.880782ms)","trace[1782961643] 'compare' (duration: 42.239921ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:19:13.211597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:19:12.879057Z","time spent":"332.466389ms","remote":"127.0.0.1:49218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:19:13.211611Z","caller":"traceutil/trace.go:171","msg":"trace[1089567024] linearizableReadLoop","detail":"{readStateIndex:8845; appliedIndex:8843; }","duration":"318.752719ms","start":"2026-06-30T18:19:12.892825Z","end":"2026-06-30T18:19:13.211578Z","steps":["trace[1089567024] 'read index received' (duration: 88.979209ms)","trace[1089567024] 'applied index is now lower than readState.Index' (duration: 229.76775ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:19:13.211655Z","caller":"traceutil/trace.go:171","msg":"trace[150169044] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"317.641548ms","start":"2026-06-30T18:19:12.89398Z","end":"2026-06-30T18:19:13.211621Z","steps":["trace[150169044] 'process raft request' (duration: 317.383283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:19:13.211762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.898832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:19:13.211798Z","caller":"traceutil/trace.go:171","msg":"trace[181260107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8548; }","duration":"308.958482ms","start":"2026-06-30T18:19:12.902829Z","end":"2026-06-30T18:19:13.211788Z","steps":["trace[181260107] 'agreement among raft nodes before linearized reading' (duration: 308.878011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:19:13.211838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:19:12.90282Z","time spent":"309.011744ms","remote":"127.0.0.1:40116","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-06-30T18:19:13.211864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:19:12.893967Z","time spent":"317.802742ms","remote":"127.0.0.1:49048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-30T18:19:13.211802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.986393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-c5bwd\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-06-30T18:19:13.212245Z","caller":"traceutil/trace.go:171","msg":"trace[2107086402] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-c5bwd; range_end:; response_count:1; response_revision:8548; }","duration":"319.447502ms","start":"2026-06-30T18:19:12.892782Z","end":"2026-06-30T18:19:13.212229Z","steps":["trace[2107086402] 'agreement among raft nodes before linearized reading' (duration: 318.911242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:19:13.212294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:19:12.892769Z","time spent":"319.512713ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4676,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-c5bwd\" "} {"level":"info","ts":"2026-06-30T18:19:54.126479Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002417.wal"} {"level":"warn","ts":"2026-06-30T18:20:30.359513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.76032ms","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":"warn","ts":"2026-06-30T18:20:30.359516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.509809ms","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-06-30T18:20:30.359647Z","caller":"traceutil/trace.go:171","msg":"trace[417130903] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9301; }","duration":"167.959573ms","start":"2026-06-30T18:20:30.191661Z","end":"2026-06-30T18:20:30.35962Z","steps":["trace[417130903] 'range keys from in-memory index tree' (duration: 167.569786ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:20:30.359645Z","caller":"traceutil/trace.go:171","msg":"trace[1578519309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9301; }","duration":"162.681603ms","start":"2026-06-30T18:20:30.196947Z","end":"2026-06-30T18:20:30.359628Z","steps":["trace[1578519309] 'range keys from in-memory index tree' (duration: 162.243965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:20:30.359557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.587042ms","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-06-30T18:20:30.359903Z","caller":"traceutil/trace.go:171","msg":"trace[410893098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9301; }","duration":"161.952658ms","start":"2026-06-30T18:20:30.197928Z","end":"2026-06-30T18:20:30.35988Z","steps":["trace[410893098] 'range keys from in-memory index tree' (duration: 161.457989ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:21:20.699022Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T18:21:20.705165Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-30T18:21:20.705279Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-30T18:23:37.55772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8270} {"level":"info","ts":"2026-06-30T18:23:37.633015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8270,"took":"72.959809ms","hash":2511743636,"current-db-size-bytes":57192448,"current-db-size":"57 MB","current-db-size-in-use-bytes":25370624,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-06-30T18:23:37.633068Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2511743636,"revision":8270,"compact-revision":6071} {"level":"info","ts":"2026-06-30T18:28:37.564858Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10762} {"level":"info","ts":"2026-06-30T18:28:37.64038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10762,"took":"73.536183ms","hash":1188053475,"current-db-size-bytes":57192448,"current-db-size":"57 MB","current-db-size-in-use-bytes":27901952,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-30T18:28:37.640605Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1188053475,"revision":10762,"compact-revision":8270} {"level":"info","ts":"2026-06-30T18:30:15.69579Z","caller":"traceutil/trace.go:171","msg":"trace[1169662467] transaction","detail":"{read_only:false; response_revision:13608; number_of_response:1; }","duration":"196.160263ms","start":"2026-06-30T18:30:15.499604Z","end":"2026-06-30T18:30:15.695764Z","steps":["trace[1169662467] 'process raft request' (duration: 196.059051ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:30:15.695995Z","caller":"traceutil/trace.go:171","msg":"trace[2091772417] transaction","detail":"{read_only:false; response_revision:13607; number_of_response:1; }","duration":"202.194797ms","start":"2026-06-30T18:30:15.493691Z","end":"2026-06-30T18:30:15.695886Z","steps":["trace[2091772417] 'process raft request' (duration: 134.368813ms)","trace[2091772417] 'compare' (duration: 67.175692ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:30:15.696076Z","caller":"traceutil/trace.go:171","msg":"trace[1043124048] linearizableReadLoop","detail":"{readStateIndex:14067; appliedIndex:14066; }","duration":"196.663842ms","start":"2026-06-30T18:30:15.499401Z","end":"2026-06-30T18:30:15.696065Z","steps":["trace[1043124048] 'read index received' (duration: 128.644625ms)","trace[1043124048] 'applied index is now lower than readState.Index' (duration: 68.018147ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:30:15.696186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.764084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:30:15.696215Z","caller":"traceutil/trace.go:171","msg":"trace[1508997766] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13608; }","duration":"196.833936ms","start":"2026-06-30T18:30:15.499372Z","end":"2026-06-30T18:30:15.696206Z","steps":["trace[1508997766] 'agreement among raft nodes before linearized reading' (duration: 196.733644ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:30:30.72886Z","caller":"traceutil/trace.go:171","msg":"trace[1408749220] transaction","detail":"{read_only:false; response_revision:13699; number_of_response:1; }","duration":"113.245594ms","start":"2026-06-30T18:30:30.615588Z","end":"2026-06-30T18:30:30.728834Z","steps":["trace[1408749220] 'process raft request' (duration: 113.0289ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:30:30.753886Z","caller":"traceutil/trace.go:171","msg":"trace[738101296] transaction","detail":"{read_only:false; response_revision:13700; number_of_response:1; }","duration":"122.548301ms","start":"2026-06-30T18:30:30.631311Z","end":"2026-06-30T18:30:30.753859Z","steps":["trace[738101296] 'process raft request' (duration: 122.358087ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:30:31.189278Z","caller":"traceutil/trace.go:171","msg":"trace[1591014923] linearizableReadLoop","detail":"{readStateIndex:14168; appliedIndex:14167; }","duration":"247.485304ms","start":"2026-06-30T18:30:30.941765Z","end":"2026-06-30T18:30:31.18925Z","steps":["trace[1591014923] 'read index received' (duration: 247.190619ms)","trace[1591014923] 'applied index is now lower than readState.Index' (duration: 293.165µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:30:31.189385Z","caller":"traceutil/trace.go:171","msg":"trace[838084] transaction","detail":"{read_only:false; response_revision:13703; number_of_response:1; }","duration":"281.781324ms","start":"2026-06-30T18:30:30.907566Z","end":"2026-06-30T18:30:31.189347Z","steps":["trace[838084] 'process raft request' (duration: 281.489039ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:30:31.189483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.751577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:30:31.189525Z","caller":"traceutil/trace.go:171","msg":"trace[825631688] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:13703; }","duration":"109.88666ms","start":"2026-06-30T18:30:31.079625Z","end":"2026-06-30T18:30:31.189512Z","steps":["trace[825631688] 'agreement among raft nodes before linearized reading' (duration: 109.783618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:30:31.189655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.858061ms","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-06-30T18:30:31.189776Z","caller":"traceutil/trace.go:171","msg":"trace[1860812450] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13703; }","duration":"248.044995ms","start":"2026-06-30T18:30:30.941709Z","end":"2026-06-30T18:30:31.189754Z","steps":["trace[1860812450] 'agreement among raft nodes before linearized reading' (duration: 247.675008ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:30:53.094156Z","caller":"traceutil/trace.go:171","msg":"trace[1257528903] linearizableReadLoop","detail":"{readStateIndex:14487; appliedIndex:14486; }","duration":"227.17144ms","start":"2026-06-30T18:30:52.866957Z","end":"2026-06-30T18:30:53.094128Z","steps":["trace[1257528903] 'read index received' (duration: 226.934396ms)","trace[1257528903] 'applied index is now lower than readState.Index' (duration: 236.184µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:30:53.094262Z","caller":"traceutil/trace.go:171","msg":"trace[1508823825] transaction","detail":"{read_only:false; response_revision:14011; number_of_response:1; }","duration":"253.444197ms","start":"2026-06-30T18:30:52.840785Z","end":"2026-06-30T18:30:53.09423Z","steps":["trace[1508823825] 'process raft request' (duration: 253.128141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:30:53.094429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.455505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-29273eab-4079-4553-9ee9-ce37336cf128\" ","response":"range_response_count:1 size:3637"} {"level":"warn","ts":"2026-06-30T18:30:53.094419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.914192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:30:53.094479Z","caller":"traceutil/trace.go:171","msg":"trace[1414738643] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-29273eab-4079-4553-9ee9-ce37336cf128; range_end:; response_count:1; response_revision:14011; }","duration":"227.523126ms","start":"2026-06-30T18:30:52.866942Z","end":"2026-06-30T18:30:53.094465Z","steps":["trace[1414738643] 'agreement among raft nodes before linearized reading' (duration: 227.337843ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:30:53.094501Z","caller":"traceutil/trace.go:171","msg":"trace[515320080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14011; }","duration":"192.030545ms","start":"2026-06-30T18:30:52.902449Z","end":"2026-06-30T18:30:53.094479Z","steps":["trace[515320080] 'agreement among raft nodes before linearized reading' (duration: 191.903042ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:32:18.848912Z","caller":"traceutil/trace.go:171","msg":"trace[699548989] linearizableReadLoop","detail":"{readStateIndex:15316; appliedIndex:15315; }","duration":"315.319048ms","start":"2026-06-30T18:32:18.533565Z","end":"2026-06-30T18:32:18.848884Z","steps":["trace[699548989] 'read index received' (duration: 315.203056ms)","trace[699548989] 'applied index is now lower than readState.Index' (duration: 114.402µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:32:18.849296Z","caller":"traceutil/trace.go:171","msg":"trace[433749128] transaction","detail":"{read_only:false; response_revision:14817; number_of_response:1; }","duration":"316.568652ms","start":"2026-06-30T18:32:18.532675Z","end":"2026-06-30T18:32:18.849244Z","steps":["trace[433749128] 'process raft request' (duration: 316.026622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:18.849414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.809177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T18:32:18.849479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:32:18.532648Z","time spent":"316.734125ms","remote":"127.0.0.1:49236","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-06-30T18:32:18.849548Z","caller":"traceutil/trace.go:171","msg":"trace[720959465] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14817; }","duration":"315.999221ms","start":"2026-06-30T18:32:18.533518Z","end":"2026-06-30T18:32:18.849518Z","steps":["trace[720959465] 'agreement among raft nodes before linearized reading' (duration: 315.772597ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:18.849608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:32:18.533499Z","time spent":"316.094652ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-30T18:32:18.892335Z","caller":"traceutil/trace.go:171","msg":"trace[1364382483] transaction","detail":"{read_only:false; response_revision:14818; number_of_response:1; }","duration":"297.369679ms","start":"2026-06-30T18:32:18.594946Z","end":"2026-06-30T18:32:18.892316Z","steps":["trace[1364382483] 'process raft request' (duration: 297.146195ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:32:18.892343Z","caller":"traceutil/trace.go:171","msg":"trace[393988027] transaction","detail":"{read_only:false; response_revision:14819; number_of_response:1; }","duration":"286.971093ms","start":"2026-06-30T18:32:18.605353Z","end":"2026-06-30T18:32:18.892324Z","steps":["trace[393988027] 'process raft request' (duration: 286.883791ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:32:18.892634Z","caller":"traceutil/trace.go:171","msg":"trace[1808519625] transaction","detail":"{read_only:false; response_revision:14820; number_of_response:1; }","duration":"287.187246ms","start":"2026-06-30T18:32:18.60542Z","end":"2026-06-30T18:32:18.892607Z","steps":["trace[1808519625] 'process raft request' (duration: 286.86423ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:32:18.892803Z","caller":"traceutil/trace.go:171","msg":"trace[968717653] transaction","detail":"{read_only:false; response_revision:14821; number_of_response:1; }","duration":"257.061107ms","start":"2026-06-30T18:32:18.635677Z","end":"2026-06-30T18:32:18.892738Z","steps":["trace[968717653] 'process raft request' (duration: 256.838752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:18.892926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.687223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T18:32:18.892952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.421834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-k4stg\" ","response":"range_response_count:1 size:20660"} {"level":"info","ts":"2026-06-30T18:32:18.892983Z","caller":"traceutil/trace.go:171","msg":"trace[507261638] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14821; }","duration":"280.788175ms","start":"2026-06-30T18:32:18.612183Z","end":"2026-06-30T18:32:18.892971Z","steps":["trace[507261638] 'agreement among raft nodes before linearized reading' (duration: 280.706673ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:32:18.892996Z","caller":"traceutil/trace.go:171","msg":"trace[1112744809] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-k4stg; range_end:; response_count:1; response_revision:14821; }","duration":"321.529936ms","start":"2026-06-30T18:32:18.571453Z","end":"2026-06-30T18:32:18.892983Z","steps":["trace[1112744809] 'agreement among raft nodes before linearized reading' (duration: 321.370323ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:18.893125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:32:18.571423Z","time spent":"321.689909ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20683,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-k4stg\" "} {"level":"warn","ts":"2026-06-30T18:32:18.893195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.50797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-30T18:32:18.893027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.825637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-30T18:32:18.893289Z","caller":"traceutil/trace.go:171","msg":"trace[1878733945] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14821; }","duration":"280.178363ms","start":"2026-06-30T18:32:18.613099Z","end":"2026-06-30T18:32:18.893277Z","steps":["trace[1878733945] 'agreement among raft nodes before linearized reading' (duration: 279.646834ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:32:18.893301Z","caller":"traceutil/trace.go:171","msg":"trace[534625142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14821; }","duration":"121.669572ms","start":"2026-06-30T18:32:18.771591Z","end":"2026-06-30T18:32:18.89326Z","steps":["trace[534625142] 'agreement among raft nodes before linearized reading' (duration: 121.330436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:18.893222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.779819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-06-30T18:32:18.893472Z","caller":"traceutil/trace.go:171","msg":"trace[2140935225] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14821; }","duration":"277.070425ms","start":"2026-06-30T18:32:18.616386Z","end":"2026-06-30T18:32:18.893457Z","steps":["trace[2140935225] 'agreement among raft nodes before linearized reading' (duration: 276.712218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:18.893217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.891441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-30T18:32:18.893577Z","caller":"traceutil/trace.go:171","msg":"trace[464278687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14821; }","duration":"258.281549ms","start":"2026-06-30T18:32:18.63528Z","end":"2026-06-30T18:32:18.893561Z","steps":["trace[464278687] 'agreement among raft nodes before linearized reading' (duration: 257.81505ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:32:24.679528Z","caller":"traceutil/trace.go:171","msg":"trace[1265828930] linearizableReadLoop","detail":"{readStateIndex:15348; appliedIndex:15347; }","duration":"133.141041ms","start":"2026-06-30T18:32:24.546354Z","end":"2026-06-30T18:32:24.679495Z","steps":["trace[1265828930] 'read index received' (duration: 53.837759ms)","trace[1265828930] 'applied index is now lower than readState.Index' (duration: 79.301352ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:32:24.679814Z","caller":"traceutil/trace.go:171","msg":"trace[77410947] transaction","detail":"{read_only:false; response_revision:14848; number_of_response:1; }","duration":"162.16469ms","start":"2026-06-30T18:32:24.517611Z","end":"2026-06-30T18:32:24.679776Z","steps":["trace[77410947] 'process raft request' (duration: 82.652304ms)","trace[77410947] 'compare' (duration: 79.042966ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:32:24.679957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.548988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lwq46\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-30T18:32:24.680029Z","caller":"traceutil/trace.go:171","msg":"trace[1158036926] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lwq46; range_end:; response_count:1; response_revision:14848; }","duration":"133.700102ms","start":"2026-06-30T18:32:24.54631Z","end":"2026-06-30T18:32:24.680011Z","steps":["trace[1158036926] 'agreement among raft nodes before linearized reading' (duration: 133.460317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:24.680038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.945296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-2knlg\" ","response":"range_response_count:1 size:22131"} {"level":"info","ts":"2026-06-30T18:32:24.680084Z","caller":"traceutil/trace.go:171","msg":"trace[1536566250] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-2knlg; range_end:; response_count:1; response_revision:14848; }","duration":"116.023737ms","start":"2026-06-30T18:32:24.564047Z","end":"2026-06-30T18:32:24.680071Z","steps":["trace[1536566250] 'agreement among raft nodes before linearized reading' (duration: 115.803633ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:32:25.061049Z","caller":"traceutil/trace.go:171","msg":"trace[1759151698] linearizableReadLoop","detail":"{readStateIndex:15350; appliedIndex:15349; }","duration":"106.952794ms","start":"2026-06-30T18:32:24.954065Z","end":"2026-06-30T18:32:25.061018Z","steps":["trace[1759151698] 'read index received' (duration: 87.241871ms)","trace[1759151698] 'applied index is now lower than readState.Index' (duration: 19.709653ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:32:25.061189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.449512ms","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-06-30T18:32:25.061283Z","caller":"traceutil/trace.go:171","msg":"trace[540250205] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14850; }","duration":"110.564053ms","start":"2026-06-30T18:32:24.950682Z","end":"2026-06-30T18:32:25.061247Z","steps":["trace[540250205] 'agreement among raft nodes before linearized reading' (duration: 110.413231ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:25.061345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.079458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-06-30T18:32:25.061401Z","caller":"traceutil/trace.go:171","msg":"trace[1051979137] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14850; }","duration":"115.15538ms","start":"2026-06-30T18:32:24.946232Z","end":"2026-06-30T18:32:25.061388Z","steps":["trace[1051979137] 'agreement among raft nodes before linearized reading' (duration: 115.008517ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:32:25.061629Z","caller":"traceutil/trace.go:171","msg":"trace[1704256412] transaction","detail":"{read_only:false; response_revision:14850; number_of_response:1; }","duration":"143.117659ms","start":"2026-06-30T18:32:24.91849Z","end":"2026-06-30T18:32:25.061608Z","steps":["trace[1704256412] 'process raft request' (duration: 122.824345ms)","trace[1704256412] 'compare' (duration: 19.606541ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:32:25.061728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.240348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-30T18:32:25.061817Z","caller":"traceutil/trace.go:171","msg":"trace[697456652] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14850; }","duration":"118.34968ms","start":"2026-06-30T18:32:24.943452Z","end":"2026-06-30T18:32:25.061802Z","steps":["trace[697456652] 'agreement among raft nodes before linearized reading' (duration: 118.165216ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:25.061985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.390111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-k4stg\" ","response":"range_response_count:1 size:20660"} {"level":"info","ts":"2026-06-30T18:32:25.062045Z","caller":"traceutil/trace.go:171","msg":"trace[1120100491] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-k4stg; range_end:; response_count:1; response_revision:14850; }","duration":"109.467372ms","start":"2026-06-30T18:32:24.952563Z","end":"2026-06-30T18:32:25.062031Z","steps":["trace[1120100491] 'agreement among raft nodes before linearized reading' (duration: 109.313049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:25.062273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.49244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:32:25.062332Z","caller":"traceutil/trace.go:171","msg":"trace[74556096] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14850; }","duration":"111.561652ms","start":"2026-06-30T18:32:24.950756Z","end":"2026-06-30T18:32:25.062318Z","steps":["trace[74556096] 'agreement among raft nodes before linearized reading' (duration: 111.476081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:25.062466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.075572ms","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-06-30T18:32:25.062631Z","caller":"traceutil/trace.go:171","msg":"trace[769572624] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14850; }","duration":"121.222674ms","start":"2026-06-30T18:32:24.941358Z","end":"2026-06-30T18:32:25.062581Z","steps":["trace[769572624] 'agreement among raft nodes before linearized reading' (duration: 120.949219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:25.06285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.350607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-30T18:32:25.062921Z","caller":"traceutil/trace.go:171","msg":"trace[1320284631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14850; }","duration":"139.434538ms","start":"2026-06-30T18:32:24.92347Z","end":"2026-06-30T18:32:25.062904Z","steps":["trace[1320284631] 'agreement among raft nodes before linearized reading' (duration: 139.284905ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:25.062516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.758286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-06-30T18:32:25.063129Z","caller":"traceutil/trace.go:171","msg":"trace[262399741] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14850; }","duration":"112.381387ms","start":"2026-06-30T18:32:24.950733Z","end":"2026-06-30T18:32:25.063114Z","steps":["trace[262399741] 'agreement among raft nodes before linearized reading' (duration: 111.699534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:25.062255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.229104ms","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-06-30T18:32:25.06337Z","caller":"traceutil/trace.go:171","msg":"trace[2083388661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14850; }","duration":"141.370226ms","start":"2026-06-30T18:32:24.921987Z","end":"2026-06-30T18:32:25.063358Z","steps":["trace[2083388661] 'agreement among raft nodes before linearized reading' (duration: 139.964509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:25.061215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.642454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:32:25.065034Z","caller":"traceutil/trace.go:171","msg":"trace[326953386] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14850; }","duration":"115.482126ms","start":"2026-06-30T18:32:24.949528Z","end":"2026-06-30T18:32:25.06501Z","steps":["trace[326953386] 'agreement among raft nodes before linearized reading' (duration: 111.649654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:25.456258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.02048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T18:32:25.456278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.929255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:32:25.456355Z","caller":"traceutil/trace.go:171","msg":"trace[1257738944] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14852; }","duration":"281.133543ms","start":"2026-06-30T18:32:25.175194Z","end":"2026-06-30T18:32:25.456327Z","steps":["trace[1257738944] 'range keys from in-memory index tree' (duration: 280.917557ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:32:25.456356Z","caller":"traceutil/trace.go:171","msg":"trace[1985079846] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14852; }","duration":"283.068348ms","start":"2026-06-30T18:32:25.173269Z","end":"2026-06-30T18:32:25.456337Z","steps":["trace[1985079846] 'range keys from in-memory index tree' (duration: 282.795853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:32:25.456389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.463228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T18:32:25.456413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.745345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-06-30T18:32:25.456457Z","caller":"traceutil/trace.go:171","msg":"trace[633654828] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14852; }","duration":"281.55561ms","start":"2026-06-30T18:32:25.17488Z","end":"2026-06-30T18:32:25.456435Z","steps":["trace[633654828] 'range keys from in-memory index tree' (duration: 281.374876ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:32:25.456511Z","caller":"traceutil/trace.go:171","msg":"trace[1087533424] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14852; }","duration":"279.878818ms","start":"2026-06-30T18:32:25.176571Z","end":"2026-06-30T18:32:25.456449Z","steps":["trace[1087533424] 'range keys from in-memory index tree' (duration: 279.566032ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:33:37.57034Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12959} {"level":"info","ts":"2026-06-30T18:33:37.644596Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12959,"took":"71.83531ms","hash":2767963476,"current-db-size-bytes":57192448,"current-db-size":"57 MB","current-db-size-in-use-bytes":30969856,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-30T18:33:37.644662Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2767963476,"revision":12959,"compact-revision":10762} {"level":"warn","ts":"2026-06-30T18:37:56.468707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.100064ms","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-06-30T18:37:56.468795Z","caller":"traceutil/trace.go:171","msg":"trace[194605755] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17345; }","duration":"166.222486ms","start":"2026-06-30T18:37:56.302552Z","end":"2026-06-30T18:37:56.468774Z","steps":["trace[194605755] 'range keys from in-memory index tree' (duration: 165.962111ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:38:02.690734Z","caller":"traceutil/trace.go:171","msg":"trace[1823148788] transaction","detail":"{read_only:false; response_revision:17382; number_of_response:1; }","duration":"180.551137ms","start":"2026-06-30T18:38:02.510148Z","end":"2026-06-30T18:38:02.690699Z","steps":["trace[1823148788] 'process raft request' (duration: 179.498947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:38:03.106338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.630195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-30T18:38:03.106472Z","caller":"traceutil/trace.go:171","msg":"trace[308006358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17384; }","duration":"174.84835ms","start":"2026-06-30T18:38:02.931589Z","end":"2026-06-30T18:38:03.106437Z","steps":["trace[308006358] 'range keys from in-memory index tree' (duration: 174.3527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:38:03.637391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.704315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:38:03.637529Z","caller":"traceutil/trace.go:171","msg":"trace[5218865] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:17385; }","duration":"428.936419ms","start":"2026-06-30T18:38:03.208565Z","end":"2026-06-30T18:38:03.637501Z","steps":["trace[5218865] 'count revisions from in-memory index tree' (duration: 428.591552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:38:03.637603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:38:03.208538Z","time spent":"429.04499ms","remote":"127.0.0.1:35050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:38:03.688048Z","caller":"traceutil/trace.go:171","msg":"trace[1287006744] linearizableReadLoop","detail":"{readStateIndex:17969; appliedIndex:17968; }","duration":"303.999713ms","start":"2026-06-30T18:38:03.384026Z","end":"2026-06-30T18:38:03.688025Z","steps":["trace[1287006744] 'read index received' (duration: 303.82711ms)","trace[1287006744] 'applied index is now lower than readState.Index' (duration: 171.593µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:38:03.688182Z","caller":"traceutil/trace.go:171","msg":"trace[181124671] transaction","detail":"{read_only:false; response_revision:17386; number_of_response:1; }","duration":"473.624164ms","start":"2026-06-30T18:38:03.214493Z","end":"2026-06-30T18:38:03.688118Z","steps":["trace[181124671] 'process raft request' (duration: 473.355179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:38:03.688278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.239557ms","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-06-30T18:38:03.688322Z","caller":"traceutil/trace.go:171","msg":"trace[682953574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17386; }","duration":"304.353509ms","start":"2026-06-30T18:38:03.383955Z","end":"2026-06-30T18:38:03.688308Z","steps":["trace[682953574] 'agreement among raft nodes before linearized reading' (duration: 304.206637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:38:03.688355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:38:03.383929Z","time spent":"304.417921ms","remote":"127.0.0.1:49236","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-06-30T18:38:03.688435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:38:03.214472Z","time spent":"473.824128ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20878,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:38:03.689068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.428003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-t6r8f\" ","response":"range_response_count:1 size:9797"} {"level":"info","ts":"2026-06-30T18:38:03.689198Z","caller":"traceutil/trace.go:171","msg":"trace[1808517852] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-t6r8f; range_end:; response_count:1; response_revision:17386; }","duration":"294.560616ms","start":"2026-06-30T18:38:03.394584Z","end":"2026-06-30T18:38:03.689145Z","steps":["trace[1808517852] 'agreement among raft nodes before linearized reading' (duration: 293.676139ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:38:37.57864Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15343} {"level":"info","ts":"2026-06-30T18:38:37.67124Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15343,"took":"89.878301ms","hash":3453203331,"current-db-size-bytes":57192448,"current-db-size":"57 MB","current-db-size-in-use-bytes":32555008,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-30T18:38:37.6713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3453203331,"revision":15343,"compact-revision":12959} {"level":"info","ts":"2026-06-30T18:42:00.915957Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T18:42:00.922927Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-30T18:42:00.923017Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-06-30T18:43:25.841006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.152332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T18:43:25.841114Z","caller":"traceutil/trace.go:171","msg":"trace[431612085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19947; }","duration":"105.328425ms","start":"2026-06-30T18:43:25.735764Z","end":"2026-06-30T18:43:25.841093Z","steps":["trace[431612085] 'range keys from in-memory index tree' (duration: 104.4938ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:43:26.099775Z","caller":"traceutil/trace.go:171","msg":"trace[1768517492] linearizableReadLoop","detail":"{readStateIndex:20614; appliedIndex:20613; }","duration":"196.637523ms","start":"2026-06-30T18:43:25.903102Z","end":"2026-06-30T18:43:26.099739Z","steps":["trace[1768517492] 'read index received' (duration: 196.44712ms)","trace[1768517492] 'applied index is now lower than readState.Index' (duration: 188.793µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:43:26.099876Z","caller":"traceutil/trace.go:171","msg":"trace[1056894977] transaction","detail":"{read_only:false; response_revision:19948; number_of_response:1; }","duration":"254.872626ms","start":"2026-06-30T18:43:25.844969Z","end":"2026-06-30T18:43:26.099842Z","steps":["trace[1056894977] 'process raft request' (duration: 254.52958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:43:26.099981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.823756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:43:26.100027Z","caller":"traceutil/trace.go:171","msg":"trace[1496702630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19948; }","duration":"196.959129ms","start":"2026-06-30T18:43:25.903058Z","end":"2026-06-30T18:43:26.100017Z","steps":["trace[1496702630] 'agreement among raft nodes before linearized reading' (duration: 196.819357ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:43:26.198284Z","caller":"traceutil/trace.go:171","msg":"trace[1293511998] transaction","detail":"{read_only:false; response_revision:19949; number_of_response:1; }","duration":"170.987358ms","start":"2026-06-30T18:43:26.027273Z","end":"2026-06-30T18:43:26.198261Z","steps":["trace[1293511998] 'process raft request' (duration: 170.619701ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:43:37.58362Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17739} {"level":"info","ts":"2026-06-30T18:43:37.69582Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17739,"took":"109.110716ms","hash":525492216,"current-db-size-bytes":57192448,"current-db-size":"57 MB","current-db-size-in-use-bytes":33177600,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-30T18:43:37.695881Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":525492216,"revision":17739,"compact-revision":15343} {"level":"info","ts":"2026-06-30T18:43:42.375346Z","caller":"traceutil/trace.go:171","msg":"trace[1056416903] transaction","detail":"{read_only:false; response_revision:20031; number_of_response:1; }","duration":"107.792711ms","start":"2026-06-30T18:43:42.267524Z","end":"2026-06-30T18:43:42.375317Z","steps":["trace[1056416903] 'process raft request' (duration: 107.624608ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:43:42.4102Z","caller":"traceutil/trace.go:171","msg":"trace[61162552] transaction","detail":"{read_only:false; response_revision:20032; number_of_response:1; }","duration":"136.752049ms","start":"2026-06-30T18:43:42.27343Z","end":"2026-06-30T18:43:42.410182Z","steps":["trace[61162552] 'process raft request' (duration: 136.646397ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:43:42.860902Z","caller":"traceutil/trace.go:171","msg":"trace[1533911236] transaction","detail":"{read_only:false; response_revision:20034; number_of_response:1; }","duration":"226.438147ms","start":"2026-06-30T18:43:42.63443Z","end":"2026-06-30T18:43:42.860868Z","steps":["trace[1533911236] 'process raft request' (duration: 203.233108ms)","trace[1533911236] 'compare' (duration: 22.847952ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:43:43.124231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.978688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:43:43.12437Z","caller":"traceutil/trace.go:171","msg":"trace[992352721] linearizableReadLoop","detail":"{readStateIndex:20706; appliedIndex:20705; }","duration":"220.153858ms","start":"2026-06-30T18:43:42.904199Z","end":"2026-06-30T18:43:43.124353Z","steps":["trace[992352721] 'read index received' (duration: 76.634471ms)","trace[992352721] 'applied index is now lower than readState.Index' (duration: 143.517947ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:43:43.124417Z","caller":"traceutil/trace.go:171","msg":"trace[1968741599] transaction","detail":"{read_only:false; response_revision:20035; number_of_response:1; }","duration":"488.981308ms","start":"2026-06-30T18:43:42.635389Z","end":"2026-06-30T18:43:43.12437Z","steps":["trace[1968741599] 'process raft request' (duration: 345.639164ms)","trace[1968741599] 'compare' (duration: 142.831365ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:43:43.124497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.056637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:43:43.124531Z","caller":"traceutil/trace.go:171","msg":"trace[1795281277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20035; }","duration":"220.131448ms","start":"2026-06-30T18:43:42.904387Z","end":"2026-06-30T18:43:43.124518Z","steps":["trace[1795281277] 'agreement among raft nodes before linearized reading' (duration: 220.075127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:43:43.124589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:43:42.635371Z","time spent":"489.132571ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:43:43.124613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.382403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-30T18:43:43.124688Z","caller":"traceutil/trace.go:171","msg":"trace[790667485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20035; }","duration":"220.503736ms","start":"2026-06-30T18:43:42.904163Z","end":"2026-06-30T18:43:43.124667Z","steps":["trace[790667485] 'agreement among raft nodes before linearized reading' (duration: 220.270891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:47:27.09801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.653793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:47:27.098134Z","caller":"traceutil/trace.go:171","msg":"trace[1611547868] linearizableReadLoop","detail":"{readStateIndex:23170; appliedIndex:23169; }","duration":"153.901854ms","start":"2026-06-30T18:47:26.944216Z","end":"2026-06-30T18:47:27.098117Z","steps":["trace[1611547868] 'read index received' (duration: 52.571µs)","trace[1611547868] 'applied index is now lower than readState.Index' (duration: 153.847923ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:47:27.098322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.111458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-30T18:47:27.09836Z","caller":"traceutil/trace.go:171","msg":"trace[168561111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22428; }","duration":"154.198119ms","start":"2026-06-30T18:47:26.944152Z","end":"2026-06-30T18:47:27.09835Z","steps":["trace[168561111] 'agreement among raft nodes before linearized reading' (duration: 154.014956ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:47:27.098671Z","caller":"traceutil/trace.go:171","msg":"trace[1184986058] transaction","detail":"{read_only:false; response_revision:22428; number_of_response:1; }","duration":"297.093576ms","start":"2026-06-30T18:47:26.801561Z","end":"2026-06-30T18:47:27.098655Z","steps":["trace[1184986058] 'process raft request' (duration: 118.677058ms)","trace[1184986058] 'compare' (duration: 177.533031ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:48:37.596032Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20009} {"level":"info","ts":"2026-06-30T18:48:37.702908Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20009,"took":"103.329187ms","hash":1201556528,"current-db-size-bytes":57192448,"current-db-size":"57 MB","current-db-size-in-use-bytes":43171840,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-06-30T18:48:37.703089Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1201556528,"revision":20009,"compact-revision":17739} {"level":"warn","ts":"2026-06-30T18:50:31.596089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.856978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:1 size:7267"} {"level":"info","ts":"2026-06-30T18:50:31.595876Z","caller":"traceutil/trace.go:171","msg":"trace[1984807818] transaction","detail":"{read_only:false; response_revision:24257; number_of_response:1; }","duration":"430.315188ms","start":"2026-06-30T18:50:31.165514Z","end":"2026-06-30T18:50:31.59583Z","steps":["trace[1984807818] 'process raft request' (duration: 429.836659ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:31.596159Z","caller":"traceutil/trace.go:171","msg":"trace[1468249537] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:1; response_revision:24258; }","duration":"346.985371ms","start":"2026-06-30T18:50:31.249162Z","end":"2026-06-30T18:50:31.596148Z","steps":["trace[1468249537] 'agreement among raft nodes before linearized reading' (duration: 346.767857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:31.596194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.249138Z","time spent":"347.050373ms","remote":"127.0.0.1:49218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":7290,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"warn","ts":"2026-06-30T18:50:31.59629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.165489Z","time spent":"430.696065ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:31.595494Z","caller":"traceutil/trace.go:171","msg":"trace[840788746] linearizableReadLoop","detail":"{readStateIndex:25044; appliedIndex:25042; }","duration":"346.254027ms","start":"2026-06-30T18:50:31.249216Z","end":"2026-06-30T18:50:31.59547Z","steps":["trace[840788746] 'read index received' (duration: 304.010367ms)","trace[840788746] 'applied index is now lower than readState.Index' (duration: 42.24286ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:31.59588Z","caller":"traceutil/trace.go:171","msg":"trace[1150269508] transaction","detail":"{read_only:false; response_revision:24258; number_of_response:1; }","duration":"155.25044ms","start":"2026-06-30T18:50:31.440594Z","end":"2026-06-30T18:50:31.595844Z","steps":["trace[1150269508] 'process raft request' (duration: 154.838243ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:31.595958Z","caller":"traceutil/trace.go:171","msg":"trace[2092435964] transaction","detail":"{read_only:false; response_revision:24256; number_of_response:1; }","duration":"438.762568ms","start":"2026-06-30T18:50:31.157092Z","end":"2026-06-30T18:50:31.595855Z","steps":["trace[2092435964] 'process raft request' (duration: 396.178542ms)","trace[2092435964] 'compare' (duration: 41.949625ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:31.596708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.157081Z","time spent":"439.589773ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:43.844031Z","caller":"traceutil/trace.go:171","msg":"trace[1942778027] transaction","detail":"{read_only:false; response_revision:24464; number_of_response:1; }","duration":"144.69594ms","start":"2026-06-30T18:50:43.699298Z","end":"2026-06-30T18:50:43.843994Z","steps":["trace[1942778027] 'process raft request' (duration: 143.396586ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:43.843998Z","caller":"traceutil/trace.go:171","msg":"trace[471836029] linearizableReadLoop","detail":"{readStateIndex:25259; appliedIndex:25258; }","duration":"134.976035ms","start":"2026-06-30T18:50:43.708989Z","end":"2026-06-30T18:50:43.843965Z","steps":["trace[471836029] 'read index received' (duration: 133.643751ms)","trace[471836029] 'applied index is now lower than readState.Index' (duration: 1.330974ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:43.844276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.258651ms","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-06-30T18:50:43.844336Z","caller":"traceutil/trace.go:171","msg":"trace[1638003932] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24464; }","duration":"135.394024ms","start":"2026-06-30T18:50:43.708928Z","end":"2026-06-30T18:50:43.844322Z","steps":["trace[1638003932] 'agreement among raft nodes before linearized reading' (duration: 135.17179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:43.844503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.475796ms","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-06-30T18:50:43.844544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.707117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-30T18:50:43.844593Z","caller":"traceutil/trace.go:171","msg":"trace[1160378518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24464; }","duration":"135.642579ms","start":"2026-06-30T18:50:43.70893Z","end":"2026-06-30T18:50:43.844573Z","steps":["trace[1160378518] 'agreement among raft nodes before linearized reading' (duration: 135.365202ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:43.84463Z","caller":"traceutil/trace.go:171","msg":"trace[97999697] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24464; }","duration":"128.844259ms","start":"2026-06-30T18:50:43.715763Z","end":"2026-06-30T18:50:43.844607Z","steps":["trace[97999697] 'agreement among raft nodes before linearized reading' (duration: 128.589355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:54.235169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.670453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:50:54.23529Z","caller":"traceutil/trace.go:171","msg":"trace[2103887061] linearizableReadLoop","detail":"{readStateIndex:25343; appliedIndex:25341; }","duration":"277.210329ms","start":"2026-06-30T18:50:53.958062Z","end":"2026-06-30T18:50:54.235272Z","steps":["trace[2103887061] 'read index received' (duration: 20.285623ms)","trace[2103887061] 'applied index is now lower than readState.Index' (duration: 256.923056ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:54.235384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.317551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:50:54.235414Z","caller":"traceutil/trace.go:171","msg":"trace[1941517010] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:24546; }","duration":"277.392252ms","start":"2026-06-30T18:50:53.958011Z","end":"2026-06-30T18:50:54.235403Z","steps":["trace[1941517010] 'agreement among raft nodes before linearized reading' (duration: 277.309341ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:54.235538Z","caller":"traceutil/trace.go:171","msg":"trace[861648477] transaction","detail":"{read_only:false; response_revision:24546; number_of_response:1; }","duration":"293.740032ms","start":"2026-06-30T18:50:53.941582Z","end":"2026-06-30T18:50:54.235322Z","steps":["trace[861648477] 'process raft request' (duration: 123.816834ms)","trace[861648477] 'compare' (duration: 169.330227ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:54.479201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.042495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:50:54.479399Z","caller":"traceutil/trace.go:171","msg":"trace[226327631] linearizableReadLoop","detail":"{readStateIndex:25346; appliedIndex:25343; }","duration":"243.121834ms","start":"2026-06-30T18:50:54.23626Z","end":"2026-06-30T18:50:54.479382Z","steps":["trace[226327631] 'read index received' (duration: 123.835675ms)","trace[226327631] 'applied index is now lower than readState.Index' (duration: 119.285009ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:54.479611Z","caller":"traceutil/trace.go:171","msg":"trace[1791095595] transaction","detail":"{read_only:false; response_revision:24549; number_of_response:1; }","duration":"245.833325ms","start":"2026-06-30T18:50:54.23373Z","end":"2026-06-30T18:50:54.479563Z","steps":["trace[1791095595] 'process raft request' (duration: 245.615551ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:54.479632Z","caller":"traceutil/trace.go:171","msg":"trace[771888150] transaction","detail":"{read_only:false; response_revision:24547; number_of_response:1; }","duration":"385.137323ms","start":"2026-06-30T18:50:54.094462Z","end":"2026-06-30T18:50:54.4796Z","steps":["trace[771888150] 'process raft request' (duration: 265.610299ms)","trace[771888150] 'compare' (duration: 118.930033ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:54.479717Z","caller":"traceutil/trace.go:171","msg":"trace[1026591914] transaction","detail":"{read_only:false; response_revision:24548; number_of_response:1; }","duration":"304.501237ms","start":"2026-06-30T18:50:54.17518Z","end":"2026-06-30T18:50:54.479682Z","steps":["trace[1026591914] 'process raft request' (duration: 304.113609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:54.479798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:54.094436Z","time spent":"385.288656ms","remote":"127.0.0.1:49236","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-06-30T18:50:54.479805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.542732ms","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-06-30T18:50:54.479848Z","caller":"traceutil/trace.go:171","msg":"trace[490716231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24549; }","duration":"243.583563ms","start":"2026-06-30T18:50:54.236252Z","end":"2026-06-30T18:50:54.479835Z","steps":["trace[490716231] 'agreement among raft nodes before linearized reading' (duration: 243.46795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:54.47988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:54.175148Z","time spent":"304.6429ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:55.361983Z","caller":"traceutil/trace.go:171","msg":"trace[1084920480] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"300.520921ms","start":"2026-06-30T18:50:55.061439Z","end":"2026-06-30T18:50:55.36196Z","steps":["trace[1084920480] 'process raft request' (duration: 300.359388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:55.362105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:55.061426Z","time spent":"300.616052ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:55.391072Z","caller":"traceutil/trace.go:171","msg":"trace[2103556656] transaction","detail":"{read_only:false; response_revision:24553; number_of_response:1; }","duration":"143.969696ms","start":"2026-06-30T18:50:55.247085Z","end":"2026-06-30T18:50:55.391054Z","steps":["trace[2103556656] 'process raft request' (duration: 143.824883ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:55.391256Z","caller":"traceutil/trace.go:171","msg":"trace[563115315] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"107.75443ms","start":"2026-06-30T18:50:55.283472Z","end":"2026-06-30T18:50:55.391227Z","steps":["trace[563115315] 'process raft request' (duration: 107.555177ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:56.196973Z","caller":"traceutil/trace.go:171","msg":"trace[82447202] linearizableReadLoop","detail":"{readStateIndex:25354; appliedIndex:25353; }","duration":"256.532869ms","start":"2026-06-30T18:50:55.940405Z","end":"2026-06-30T18:50:56.196938Z","steps":["trace[82447202] 'read index received' (duration: 256.175682ms)","trace[82447202] 'applied index is now lower than readState.Index' (duration: 355.577µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:56.197389Z","caller":"traceutil/trace.go:171","msg":"trace[223113705] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"274.719492ms","start":"2026-06-30T18:50:55.922637Z","end":"2026-06-30T18:50:56.197357Z","steps":["trace[223113705] 'process raft request' (duration: 274.0385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:56.19777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.341403ms","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-06-30T18:50:56.19784Z","caller":"traceutil/trace.go:171","msg":"trace[1297806528] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24557; }","duration":"257.485306ms","start":"2026-06-30T18:50:55.940333Z","end":"2026-06-30T18:50:56.197818Z","steps":["trace[1297806528] 'agreement among raft nodes before linearized reading' (duration: 257.300472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:56.212352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.194139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-30T18:50:56.212418Z","caller":"traceutil/trace.go:171","msg":"trace[367995224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24558; }","duration":"229.300732ms","start":"2026-06-30T18:50:55.983102Z","end":"2026-06-30T18:50:56.212403Z","steps":["trace[367995224] 'agreement among raft nodes before linearized reading' (duration: 229.138408ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:56.372766Z","caller":"traceutil/trace.go:171","msg":"trace[5855484] linearizableReadLoop","detail":"{readStateIndex:25357; appliedIndex:25356; }","duration":"132.701774ms","start":"2026-06-30T18:50:56.240037Z","end":"2026-06-30T18:50:56.372739Z","steps":["trace[5855484] 'read index received' (duration: 119.564705ms)","trace[5855484] 'applied index is now lower than readState.Index' (duration: 13.135949ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:56.372832Z","caller":"traceutil/trace.go:171","msg":"trace[2140008468] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"152.958577ms","start":"2026-06-30T18:50:56.219836Z","end":"2026-06-30T18:50:56.372794Z","steps":["trace[2140008468] 'process raft request' (duration: 139.786297ms)","trace[2140008468] 'compare' (duration: 12.971106ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:56.373074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.009089ms","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-06-30T18:50:56.373149Z","caller":"traceutil/trace.go:171","msg":"trace[1995589781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24559; }","duration":"133.141992ms","start":"2026-06-30T18:50:56.239988Z","end":"2026-06-30T18:50:56.37313Z","steps":["trace[1995589781] 'agreement among raft nodes before linearized reading' (duration: 132.877167ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:56.717739Z","caller":"traceutil/trace.go:171","msg":"trace[1763396660] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"231.546315ms","start":"2026-06-30T18:50:56.486158Z","end":"2026-06-30T18:50:56.717705Z","steps":["trace[1763396660] 'process raft request' (duration: 219.012687ms)","trace[1763396660] 'compare' (duration: 12.391505ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:56.717878Z","caller":"traceutil/trace.go:171","msg":"trace[779851394] linearizableReadLoop","detail":"{readStateIndex:25361; appliedIndex:25358; }","duration":"217.691083ms","start":"2026-06-30T18:50:56.500162Z","end":"2026-06-30T18:50:56.717853Z","steps":["trace[779851394] 'read index received' (duration: 49.891895ms)","trace[779851394] 'applied index is now lower than readState.Index' (duration: 167.797148ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:56.717989Z","caller":"traceutil/trace.go:171","msg":"trace[1834574108] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"228.337615ms","start":"2026-06-30T18:50:56.489612Z","end":"2026-06-30T18:50:56.71795Z","steps":["trace[1834574108] 'process raft request' (duration: 228.060969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:56.718076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.903026ms","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-06-30T18:50:56.718119Z","caller":"traceutil/trace.go:171","msg":"trace[1101526572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24563; }","duration":"218.000079ms","start":"2026-06-30T18:50:56.500109Z","end":"2026-06-30T18:50:56.718109Z","steps":["trace[1101526572] 'agreement among raft nodes before linearized reading' (duration: 217.820885ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:56.946632Z","caller":"traceutil/trace.go:171","msg":"trace[1132355918] linearizableReadLoop","detail":"{readStateIndex:25362; appliedIndex:25361; }","duration":"165.262969ms","start":"2026-06-30T18:50:56.781345Z","end":"2026-06-30T18:50:56.946608Z","steps":["trace[1132355918] 'read index received' (duration: 81.589595ms)","trace[1132355918] 'applied index is now lower than readState.Index' (duration: 83.672134ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:56.946933Z","caller":"traceutil/trace.go:171","msg":"trace[1619619843] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"224.239046ms","start":"2026-06-30T18:50:56.722654Z","end":"2026-06-30T18:50:56.946893Z","steps":["trace[1619619843] 'process raft request' (duration: 140.268696ms)","trace[1619619843] 'compare' (duration: 83.577102ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:56.947208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.84588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T18:50:56.947241Z","caller":"traceutil/trace.go:171","msg":"trace[1663347711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24564; }","duration":"165.921622ms","start":"2026-06-30T18:50:56.78131Z","end":"2026-06-30T18:50:56.947231Z","steps":["trace[1663347711] 'agreement among raft nodes before linearized reading' (duration: 165.794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:56.947403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.373636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:56.947438Z","caller":"traceutil/trace.go:171","msg":"trace[905450788] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24564; }","duration":"133.445038ms","start":"2026-06-30T18:50:56.813979Z","end":"2026-06-30T18:50:56.947424Z","steps":["trace[905450788] 'agreement among raft nodes before linearized reading' (duration: 133.390947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:57.473692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.272402ms","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-06-30T18:50:57.47376Z","caller":"traceutil/trace.go:171","msg":"trace[645563395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24566; }","duration":"107.454206ms","start":"2026-06-30T18:50:57.366287Z","end":"2026-06-30T18:50:57.473741Z","steps":["trace[645563395] 'range keys from in-memory index tree' (duration: 107.060817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:57.474182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.278226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:57.474209Z","caller":"traceutil/trace.go:171","msg":"trace[229818583] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:24566; }","duration":"169.339208ms","start":"2026-06-30T18:50:57.304863Z","end":"2026-06-30T18:50:57.474202Z","steps":["trace[229818583] 'count revisions from in-memory index tree' (duration: 169.204934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:57.867638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.651657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:50:57.868074Z","caller":"traceutil/trace.go:171","msg":"trace[119385967] transaction","detail":"{read_only:false; response_revision:24568; number_of_response:1; }","duration":"471.276635ms","start":"2026-06-30T18:50:57.396743Z","end":"2026-06-30T18:50:57.86802Z","steps":["trace[119385967] 'process raft request' (duration: 285.735081ms)","trace[119385967] 'compare' (duration: 184.541575ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:57.868198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:57.396714Z","time spent":"471.423098ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:57.8686Z","caller":"traceutil/trace.go:171","msg":"trace[491382284] linearizableReadLoop","detail":"{readStateIndex:25367; appliedIndex:25365; }","duration":"367.799866ms","start":"2026-06-30T18:50:57.50079Z","end":"2026-06-30T18:50:57.86859Z","steps":["trace[491382284] 'read index received' (duration: 181.691681ms)","trace[491382284] 'applied index is now lower than readState.Index' (duration: 186.107545ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:57.868624Z","caller":"traceutil/trace.go:171","msg":"trace[982102175] transaction","detail":"{read_only:false; response_revision:24569; number_of_response:1; }","duration":"390.556346ms","start":"2026-06-30T18:50:57.478049Z","end":"2026-06-30T18:50:57.868605Z","steps":["trace[982102175] 'process raft request' (duration: 389.841212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:57.868715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:57.478029Z","time spent":"390.641909ms","remote":"127.0.0.1:49236","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-06-30T18:50:57.868774Z","caller":"traceutil/trace.go:171","msg":"trace[484248428] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"281.519862ms","start":"2026-06-30T18:50:57.587214Z","end":"2026-06-30T18:50:57.868734Z","steps":["trace[484248428] 'process raft request' (duration: 281.341558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:57.868913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.121822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-30T18:50:57.868952Z","caller":"traceutil/trace.go:171","msg":"trace[1438154344] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24570; }","duration":"368.197974ms","start":"2026-06-30T18:50:57.500742Z","end":"2026-06-30T18:50:57.86894Z","steps":["trace[1438154344] 'agreement among raft nodes before linearized reading' (duration: 368.053671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:57.868985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:57.50072Z","time spent":"368.259084ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-30T18:50:57.869057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.792321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:57.869153Z","caller":"traceutil/trace.go:171","msg":"trace[166622786] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:24570; }","duration":"342.923393ms","start":"2026-06-30T18:50:57.52622Z","end":"2026-06-30T18:50:57.869143Z","steps":["trace[166622786] 'agreement among raft nodes before linearized reading' (duration: 342.698239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:57.869187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.331588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:57.869246Z","caller":"traceutil/trace.go:171","msg":"trace[564683197] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:24570; }","duration":"158.447811ms","start":"2026-06-30T18:50:57.710784Z","end":"2026-06-30T18:50:57.869231Z","steps":["trace[564683197] 'agreement among raft nodes before linearized reading' (duration: 158.287568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:57.86922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:57.526201Z","time spent":"343.008336ms","remote":"127.0.0.1:34562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:50:58.492556Z","caller":"traceutil/trace.go:171","msg":"trace[320716511] linearizableReadLoop","detail":"{readStateIndex:25370; appliedIndex:25369; }","duration":"271.605023ms","start":"2026-06-30T18:50:58.220928Z","end":"2026-06-30T18:50:58.492533Z","steps":["trace[320716511] 'read index received' (duration: 271.402669ms)","trace[320716511] 'applied index is now lower than readState.Index' (duration: 201.494µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:58.492832Z","caller":"traceutil/trace.go:171","msg":"trace[1063403389] transaction","detail":"{read_only:false; response_revision:24572; number_of_response:1; }","duration":"287.193258ms","start":"2026-06-30T18:50:58.205626Z","end":"2026-06-30T18:50:58.492819Z","steps":["trace[1063403389] 'process raft request' (duration: 286.77079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:58.493127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.189285ms","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-06-30T18:50:58.493164Z","caller":"traceutil/trace.go:171","msg":"trace[1764398349] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24572; }","duration":"272.239206ms","start":"2026-06-30T18:50:58.220912Z","end":"2026-06-30T18:50:58.493151Z","steps":["trace[1764398349] 'agreement among raft nodes before linearized reading' (duration: 272.088193ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:58.494913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.635148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-30T18:50:58.495003Z","caller":"traceutil/trace.go:171","msg":"trace[1220280880] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24572; }","duration":"117.767401ms","start":"2026-06-30T18:50:58.377214Z","end":"2026-06-30T18:50:58.494981Z","steps":["trace[1220280880] 'agreement among raft nodes before linearized reading' (duration: 117.529646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:58.495737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.091687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:58.495794Z","caller":"traceutil/trace.go:171","msg":"trace[250217737] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24572; }","duration":"211.205699ms","start":"2026-06-30T18:50:58.284575Z","end":"2026-06-30T18:50:58.495781Z","steps":["trace[250217737] 'agreement among raft nodes before linearized reading' (duration: 208.754513ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:59.086847Z","caller":"traceutil/trace.go:171","msg":"trace[2018364969] transaction","detail":"{read_only:false; response_revision:24573; number_of_response:1; }","duration":"586.848983ms","start":"2026-06-30T18:50:58.499965Z","end":"2026-06-30T18:50:59.086814Z","steps":["trace[2018364969] 'process raft request' (duration: 527.56018ms)","trace[2018364969] 'compare' (duration: 58.866654ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:59.087003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:58.49995Z","time spent":"586.968555ms","remote":"127.0.0.1:49236","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-06-30T18:50:59.129072Z","caller":"traceutil/trace.go:171","msg":"trace[1595295093] linearizableReadLoop","detail":"{readStateIndex:25374; appliedIndex:25370; }","duration":"549.020036ms","start":"2026-06-30T18:50:58.580023Z","end":"2026-06-30T18:50:59.129043Z","steps":["trace[1595295093] 'read index received' (duration: 447.650346ms)","trace[1595295093] 'applied index is now lower than readState.Index' (duration: 101.36864ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:59.129439Z","caller":"traceutil/trace.go:171","msg":"trace[906476225] transaction","detail":"{read_only:false; response_revision:24574; number_of_response:1; }","duration":"628.92884ms","start":"2026-06-30T18:50:58.500494Z","end":"2026-06-30T18:50:59.129422Z","steps":["trace[906476225] 'process raft request' (duration: 628.250217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:59.129571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:58.500472Z","time spent":"629.013661ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:59.129779Z","caller":"traceutil/trace.go:171","msg":"trace[442027278] transaction","detail":"{read_only:false; response_revision:24575; number_of_response:1; }","duration":"622.249283ms","start":"2026-06-30T18:50:58.507519Z","end":"2026-06-30T18:50:59.129768Z","steps":["trace[442027278] 'process raft request' (duration: 621.356697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:59.129877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:58.5075Z","time spent":"622.308944ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:59.130065Z","caller":"traceutil/trace.go:171","msg":"trace[2059654574] transaction","detail":"{read_only:false; response_revision:24576; number_of_response:1; }","duration":"550.454773ms","start":"2026-06-30T18:50:58.579597Z","end":"2026-06-30T18:50:59.130052Z","steps":["trace[2059654574] 'process raft request' (duration: 549.322772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:59.130149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:58.579557Z","time spent":"550.547945ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:59.130333Z","caller":"traceutil/trace.go:171","msg":"trace[2000682023] transaction","detail":"{read_only:false; response_revision:24577; number_of_response:1; }","duration":"405.468579ms","start":"2026-06-30T18:50:58.724854Z","end":"2026-06-30T18:50:59.130322Z","steps":["trace[2000682023] 'process raft request' (duration: 404.108623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:59.130403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:58.724826Z","time spent":"405.540469ms","remote":"127.0.0.1:49236","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-06-30T18:50:59.130567Z","caller":"traceutil/trace.go:171","msg":"trace[41519379] transaction","detail":"{read_only:false; response_revision:24578; number_of_response:1; }","duration":"405.383767ms","start":"2026-06-30T18:50:58.72517Z","end":"2026-06-30T18:50:59.130554Z","steps":["trace[41519379] 'process raft request' (duration: 403.833918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:59.130625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:58.725145Z","time spent":"405.450358ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:59.130848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.82993ms","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-06-30T18:50:59.130886Z","caller":"traceutil/trace.go:171","msg":"trace[2586538] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24578; }","duration":"550.909611ms","start":"2026-06-30T18:50:58.579967Z","end":"2026-06-30T18:50:59.130877Z","steps":["trace[2586538] 'agreement among raft nodes before linearized reading' (duration: 550.777228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:59.130924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:58.57993Z","time spent":"550.982143ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-30T18:50:59.131137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.470618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:59.131178Z","caller":"traceutil/trace.go:171","msg":"trace[1630857716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24578; }","duration":"226.552959ms","start":"2026-06-30T18:50:58.904615Z","end":"2026-06-30T18:50:59.131168Z","steps":["trace[1630857716] 'agreement among raft nodes before linearized reading' (duration: 226.480988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:59.131663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.225621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T18:50:59.131701Z","caller":"traceutil/trace.go:171","msg":"trace[795760929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24578; }","duration":"106.265242ms","start":"2026-06-30T18:50:59.025426Z","end":"2026-06-30T18:50:59.131692Z","steps":["trace[795760929] 'agreement among raft nodes before linearized reading' (duration: 106.15549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:59.131882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.118352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-30T18:50:59.132836Z","caller":"traceutil/trace.go:171","msg":"trace[115721049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24578; }","duration":"180.08729ms","start":"2026-06-30T18:50:58.952732Z","end":"2026-06-30T18:50:59.132819Z","steps":["trace[115721049] 'agreement among raft nodes before linearized reading' (duration: 179.092952ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:59.490557Z","caller":"traceutil/trace.go:171","msg":"trace[1416753297] transaction","detail":"{read_only:false; response_revision:24579; number_of_response:1; }","duration":"351.402985ms","start":"2026-06-30T18:50:59.139137Z","end":"2026-06-30T18:50:59.49054Z","steps":["trace[1416753297] 'process raft request' (duration: 351.291473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:59.49067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:59.139124Z","time spent":"351.485896ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:59.531388Z","caller":"traceutil/trace.go:171","msg":"trace[1660589882] transaction","detail":"{read_only:false; response_revision:24581; number_of_response:1; }","duration":"391.151656ms","start":"2026-06-30T18:50:59.140216Z","end":"2026-06-30T18:50:59.531367Z","steps":["trace[1660589882] 'process raft request' (duration: 391.085205ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:59.531434Z","caller":"traceutil/trace.go:171","msg":"trace[1523419962] transaction","detail":"{read_only:false; response_revision:24580; number_of_response:1; }","duration":"391.532673ms","start":"2026-06-30T18:50:59.139874Z","end":"2026-06-30T18:50:59.531406Z","steps":["trace[1523419962] 'process raft request' (duration: 391.089695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:59.531522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:59.140186Z","time spent":"391.266048ms","remote":"127.0.0.1:49236","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-06-30T18:50:59.531586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:59.139838Z","time spent":"391.663046ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:59.531647Z","caller":"traceutil/trace.go:171","msg":"trace[1405928637] linearizableReadLoop","detail":"{readStateIndex:25379; appliedIndex:25377; }","duration":"201.624757ms","start":"2026-06-30T18:50:59.330014Z","end":"2026-06-30T18:50:59.531639Z","steps":["trace[1405928637] 'read index received' (duration: 160.546321ms)","trace[1405928637] 'applied index is now lower than readState.Index' (duration: 41.077766ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:59.531733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.718779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:50:59.531764Z","caller":"traceutil/trace.go:171","msg":"trace[1595106623] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:24582; }","duration":"201.809911ms","start":"2026-06-30T18:50:59.329944Z","end":"2026-06-30T18:50:59.531754Z","steps":["trace[1595106623] 'agreement among raft nodes before linearized reading' (duration: 201.725929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:59.765785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.0892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:59.765872Z","caller":"traceutil/trace.go:171","msg":"trace[653740029] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24582; }","duration":"105.228523ms","start":"2026-06-30T18:50:59.660621Z","end":"2026-06-30T18:50:59.76585Z","steps":["trace[653740029] 'count revisions from in-memory index tree' (duration: 105.018679ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:00.06704Z","caller":"traceutil/trace.go:171","msg":"trace[1793741291] transaction","detail":"{read_only:false; response_revision:24583; number_of_response:1; }","duration":"192.518035ms","start":"2026-06-30T18:50:59.874481Z","end":"2026-06-30T18:51:00.066999Z","steps":["trace[1793741291] 'process raft request' (duration: 192.343842ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:00.581968Z","caller":"traceutil/trace.go:171","msg":"trace[66688383] linearizableReadLoop","detail":"{readStateIndex:25382; appliedIndex:25380; }","duration":"634.82714ms","start":"2026-06-30T18:50:59.947121Z","end":"2026-06-30T18:51:00.581948Z","steps":["trace[66688383] 'read index received' (duration: 119.797338ms)","trace[66688383] 'applied index is now lower than readState.Index' (duration: 515.029042ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:00.582115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.991874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:00.582139Z","caller":"traceutil/trace.go:171","msg":"trace[438760819] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24585; }","duration":"635.084085ms","start":"2026-06-30T18:50:59.947046Z","end":"2026-06-30T18:51:00.58213Z","steps":["trace[438760819] 'agreement among raft nodes before linearized reading' (duration: 634.971963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:00.582165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:59.947016Z","time spent":"635.140606ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:51:00.582403Z","caller":"traceutil/trace.go:171","msg":"trace[1853965022] transaction","detail":"{read_only:false; response_revision:24584; number_of_response:1; }","duration":"705.146251ms","start":"2026-06-30T18:50:59.877247Z","end":"2026-06-30T18:51:00.582393Z","steps":["trace[1853965022] 'process raft request' (duration: 670.614117ms)","trace[1853965022] 'compare' (duration: 33.934153ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:00.582462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:59.877233Z","time spent":"705.187512ms","remote":"127.0.0.1:49236","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-06-30T18:51:00.582558Z","caller":"traceutil/trace.go:171","msg":"trace[1412014801] transaction","detail":"{read_only:false; response_revision:24585; number_of_response:1; }","duration":"615.837981ms","start":"2026-06-30T18:50:59.966712Z","end":"2026-06-30T18:51:00.58255Z","steps":["trace[1412014801] 'process raft request' (duration: 615.188208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:00.582591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:59.966647Z","time spent":"615.924432ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:01.139704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.872074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:01.139851Z","caller":"traceutil/trace.go:171","msg":"trace[2124176167] linearizableReadLoop","detail":"{readStateIndex:25384; appliedIndex:25383; }","duration":"360.414304ms","start":"2026-06-30T18:51:00.779413Z","end":"2026-06-30T18:51:01.139827Z","steps":["trace[2124176167] 'read index received' (duration: 56.215834ms)","trace[2124176167] 'applied index is now lower than readState.Index' (duration: 304.19646ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:01.140264Z","caller":"traceutil/trace.go:171","msg":"trace[1436019905] transaction","detail":"{read_only:false; response_revision:24586; number_of_response:1; }","duration":"640.530569ms","start":"2026-06-30T18:51:00.49971Z","end":"2026-06-30T18:51:01.140241Z","steps":["trace[1436019905] 'process raft request' (duration: 335.980852ms)","trace[1436019905] 'compare' (duration: 303.182031ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:01.140439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:00.499677Z","time spent":"640.669541ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:01.140786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.364363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418399"} {"level":"info","ts":"2026-06-30T18:51:01.140855Z","caller":"traceutil/trace.go:171","msg":"trace[825980169] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24586; }","duration":"361.473184ms","start":"2026-06-30T18:51:00.779365Z","end":"2026-06-30T18:51:01.140838Z","steps":["trace[825980169] 'agreement among raft nodes before linearized reading' (duration: 361.104548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:01.141324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:00.779344Z","time spent":"361.827712ms","remote":"127.0.0.1:47942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418422,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-06-30T18:51:01.630936Z","caller":"traceutil/trace.go:171","msg":"trace[1710710667] transaction","detail":"{read_only:false; response_revision:24587; number_of_response:1; }","duration":"492.422144ms","start":"2026-06-30T18:51:01.138491Z","end":"2026-06-30T18:51:01.630913Z","steps":["trace[1710710667] 'process raft request' (duration: 477.9425ms)","trace[1710710667] 'compare' (duration: 14.113048ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:01.631053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:01.138469Z","time spent":"492.522207ms","remote":"127.0.0.1:49236","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-06-30T18:51:02.277675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.363309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:02.277873Z","caller":"traceutil/trace.go:171","msg":"trace[1408966638] transaction","detail":"{read_only:false; response_revision:24588; number_of_response:1; }","duration":"1.139016628s","start":"2026-06-30T18:51:01.138843Z","end":"2026-06-30T18:51:02.27786Z","steps":["trace[1408966638] 'process raft request' (duration: 962.402115ms)","trace[1408966638] 'compare' (duration: 175.42122ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:02.277919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:01.138827Z","time spent":"1.139071529s","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:02.278063Z","caller":"traceutil/trace.go:171","msg":"trace[774725626] transaction","detail":"{read_only:false; response_revision:24589; number_of_response:1; }","duration":"1.138315335s","start":"2026-06-30T18:51:01.13974Z","end":"2026-06-30T18:51:02.278055Z","steps":["trace[774725626] 'process raft request' (duration: 1.13804394s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:02.278096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:01.139733Z","time spent":"1.138343845s","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:02.278557Z","caller":"traceutil/trace.go:171","msg":"trace[1998568837] transaction","detail":"{read_only:false; response_revision:24590; number_of_response:1; }","duration":"740.555102ms","start":"2026-06-30T18:51:01.537994Z","end":"2026-06-30T18:51:02.278549Z","steps":["trace[1998568837] 'process raft request' (duration: 740.027542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:02.278599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:01.537957Z","time spent":"740.619394ms","remote":"127.0.0.1:49236","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-06-30T18:51:02.27917Z","caller":"traceutil/trace.go:171","msg":"trace[198701160] transaction","detail":"{read_only:false; response_revision:24593; number_of_response:1; }","duration":"205.877317ms","start":"2026-06-30T18:51:02.073256Z","end":"2026-06-30T18:51:02.279133Z","steps":["trace[198701160] 'process raft request' (duration: 205.833226ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:02.279527Z","caller":"traceutil/trace.go:171","msg":"trace[393975960] transaction","detail":"{read_only:false; response_revision:24591; number_of_response:1; }","duration":"729.420511ms","start":"2026-06-30T18:51:01.550095Z","end":"2026-06-30T18:51:02.279516Z","steps":["trace[393975960] 'process raft request' (duration: 728.556005ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:02.27954Z","caller":"traceutil/trace.go:171","msg":"trace[14745597] linearizableReadLoop","detail":"{readStateIndex:25387; appliedIndex:25384; }","duration":"1.139645339s","start":"2026-06-30T18:51:01.139886Z","end":"2026-06-30T18:51:02.279532Z","steps":["trace[14745597] 'read index received' (duration: 476.492863ms)","trace[14745597] 'applied index is now lower than readState.Index' (duration: 663.151916ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:02.279545Z","caller":"traceutil/trace.go:171","msg":"trace[650140945] transaction","detail":"{read_only:false; response_revision:24592; number_of_response:1; }","duration":"724.762753ms","start":"2026-06-30T18:51:01.554729Z","end":"2026-06-30T18:51:02.279492Z","steps":["trace[650140945] 'process raft request' (duration: 724.149681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:02.279619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:01.550077Z","time spent":"729.475652ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:02.279662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:01.554695Z","time spent":"724.898446ms","remote":"127.0.0.1:47942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418382,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:02.279706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188512555s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-06-30T18:51:02.279723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377559224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-30T18:51:02.279679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153651374s","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-06-30T18:51:02.279756Z","caller":"traceutil/trace.go:171","msg":"trace[474472641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24593; }","duration":"1.377621665s","start":"2026-06-30T18:51:00.902125Z","end":"2026-06-30T18:51:02.279747Z","steps":["trace[474472641] 'agreement among raft nodes before linearized reading' (duration: 1.377567494s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:02.279785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:00.902105Z","time spent":"1.377674666s","remote":"127.0.0.1:40116","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-06-30T18:51:02.279789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440391564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-30T18:51:02.279808Z","caller":"traceutil/trace.go:171","msg":"trace[1872547126] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:24593; }","duration":"1.440444706s","start":"2026-06-30T18:51:00.839359Z","end":"2026-06-30T18:51:02.279803Z","steps":["trace[1872547126] 'agreement among raft nodes before linearized reading' (duration: 1.440395105s)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:02.279788Z","caller":"traceutil/trace.go:171","msg":"trace[1986561929] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24593; }","duration":"1.153791587s","start":"2026-06-30T18:51:01.125972Z","end":"2026-06-30T18:51:02.279764Z","steps":["trace[1986561929] 'agreement among raft nodes before linearized reading' (duration: 1.153586623s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:02.279823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:00.839339Z","time spent":"1.440480526s","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-06-30T18:51:02.279784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140105729s","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":"warn","ts":"2026-06-30T18:51:02.279851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:01.12591Z","time spent":"1.15392214s","remote":"127.0.0.1:45854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:51:02.279869Z","caller":"traceutil/trace.go:171","msg":"trace[2097720516] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24593; }","duration":"1.1402126s","start":"2026-06-30T18:51:01.139635Z","end":"2026-06-30T18:51:02.279847Z","steps":["trace[2097720516] 'agreement among raft nodes before linearized reading' (duration: 1.139972906s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:02.279951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:01.139628Z","time spent":"1.140310032s","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-30T18:51:02.27974Z","caller":"traceutil/trace.go:171","msg":"trace[1049728628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24593; }","duration":"1.188676398s","start":"2026-06-30T18:51:01.091058Z","end":"2026-06-30T18:51:02.279734Z","steps":["trace[1049728628] 'agreement among raft nodes before linearized reading' (duration: 1.188520375s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:02.280011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142091585s","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-06-30T18:51:02.280066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:01.091035Z","time spent":"1.189019534s","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-30T18:51:02.28008Z","caller":"traceutil/trace.go:171","msg":"trace[610161244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24593; }","duration":"1.142195797s","start":"2026-06-30T18:51:01.137868Z","end":"2026-06-30T18:51:02.280063Z","steps":["trace[610161244] 'agreement among raft nodes before linearized reading' (duration: 1.141983413s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:02.28012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:01.137851Z","time spent":"1.142260479s","remote":"127.0.0.1:49236","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-06-30T18:51:02.348649Z","caller":"traceutil/trace.go:171","msg":"trace[519939284] transaction","detail":"{read_only:false; response_revision:24594; number_of_response:1; }","duration":"158.519362ms","start":"2026-06-30T18:51:02.190094Z","end":"2026-06-30T18:51:02.348614Z","steps":["trace[519939284] 'process raft request' (duration: 158.113955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:02.349112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.066928ms","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":"info","ts":"2026-06-30T18:51:02.349163Z","caller":"traceutil/trace.go:171","msg":"trace[185593097] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24594; }","duration":"736.16768ms","start":"2026-06-30T18:51:01.612983Z","end":"2026-06-30T18:51:02.34915Z","steps":["trace[185593097] 'agreement among raft nodes before linearized reading' (duration: 736.006117ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:02.349356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:01.612965Z","time spent":"736.378414ms","remote":"127.0.0.1:49236","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-06-30T18:51:02.34959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.94474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-30T18:51:02.349686Z","caller":"traceutil/trace.go:171","msg":"trace[1348439421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24594; }","duration":"854.092583ms","start":"2026-06-30T18:51:01.495579Z","end":"2026-06-30T18:51:02.349672Z","steps":["trace[1348439421] 'agreement among raft nodes before linearized reading' (duration: 853.806108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:02.349787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:01.495559Z","time spent":"854.218345ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T18:51:02.34995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.085227ms","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-06-30T18:51:02.349991Z","caller":"traceutil/trace.go:171","msg":"trace[786231124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24594; }","duration":"812.155068ms","start":"2026-06-30T18:51:01.537825Z","end":"2026-06-30T18:51:02.34998Z","steps":["trace[786231124] 'agreement among raft nodes before linearized reading' (duration: 812.045226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:02.350024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:01.53781Z","time spent":"812.207559ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-30T18:51:04.535774Z","caller":"traceutil/trace.go:171","msg":"trace[1427299587] transaction","detail":"{read_only:false; response_revision:24612; number_of_response:1; }","duration":"122.330247ms","start":"2026-06-30T18:51:04.413418Z","end":"2026-06-30T18:51:04.535749Z","steps":["trace[1427299587] 'process raft request' (duration: 122.215885ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:04.535832Z","caller":"traceutil/trace.go:171","msg":"trace[1830082954] transaction","detail":"{read_only:false; response_revision:24611; number_of_response:1; }","duration":"122.549391ms","start":"2026-06-30T18:51:04.413244Z","end":"2026-06-30T18:51:04.535794Z","steps":["trace[1830082954] 'process raft request' (duration: 120.739036ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:04.536339Z","caller":"traceutil/trace.go:171","msg":"trace[1256522368] transaction","detail":"{read_only:false; response_revision:24614; number_of_response:1; }","duration":"122.550611ms","start":"2026-06-30T18:51:04.413741Z","end":"2026-06-30T18:51:04.536291Z","steps":["trace[1256522368] 'process raft request' (duration: 122.296006ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:04.53677Z","caller":"traceutil/trace.go:171","msg":"trace[1525086732] transaction","detail":"{read_only:false; response_revision:24613; number_of_response:1; }","duration":"123.08802ms","start":"2026-06-30T18:51:04.413667Z","end":"2026-06-30T18:51:04.536755Z","steps":["trace[1525086732] 'process raft request' (duration: 122.039361ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:10.501063Z","caller":"traceutil/trace.go:171","msg":"trace[2013713242] transaction","detail":"{read_only:false; response_revision:24648; number_of_response:1; }","duration":"155.720398ms","start":"2026-06-30T18:51:10.345321Z","end":"2026-06-30T18:51:10.501041Z","steps":["trace[2013713242] 'process raft request' (duration: 155.525594ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:10.999578Z","caller":"traceutil/trace.go:171","msg":"trace[544476528] transaction","detail":"{read_only:false; response_revision:24649; number_of_response:1; }","duration":"644.011136ms","start":"2026-06-30T18:51:10.35554Z","end":"2026-06-30T18:51:10.999551Z","steps":["trace[544476528] 'process raft request' (duration: 643.71683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:10.999782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:10.35552Z","time spent":"644.160248ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:10.999836Z","caller":"traceutil/trace.go:171","msg":"trace[1668719255] linearizableReadLoop","detail":"{readStateIndex:25451; appliedIndex:25448; }","duration":"533.298049ms","start":"2026-06-30T18:51:10.466521Z","end":"2026-06-30T18:51:10.999819Z","steps":["trace[1668719255] 'read index received' (duration: 34.789009ms)","trace[1668719255] 'applied index is now lower than readState.Index' (duration: 498.508159ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:10.999956Z","caller":"traceutil/trace.go:171","msg":"trace[672172272] transaction","detail":"{read_only:false; response_revision:24651; number_of_response:1; }","duration":"619.092243ms","start":"2026-06-30T18:51:10.380835Z","end":"2026-06-30T18:51:10.999928Z","steps":["trace[672172272] 'process raft request' (duration: 618.899149ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:10.999965Z","caller":"traceutil/trace.go:171","msg":"trace[2098758479] transaction","detail":"{read_only:false; response_revision:24650; number_of_response:1; }","duration":"619.378289ms","start":"2026-06-30T18:51:10.380503Z","end":"2026-06-30T18:51:10.999882Z","steps":["trace[2098758479] 'process raft request' (duration: 618.992141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.000032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.511512ms","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-06-30T18:51:11.000085Z","caller":"traceutil/trace.go:171","msg":"trace[1229877210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24651; }","duration":"533.606544ms","start":"2026-06-30T18:51:10.466464Z","end":"2026-06-30T18:51:11.000071Z","steps":["trace[1229877210] 'agreement among raft nodes before linearized reading' (duration: 533.437081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.000125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:10.466443Z","time spent":"533.673635ms","remote":"127.0.0.1:49236","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-06-30T18:51:11.000117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:10.38082Z","time spent":"619.209665ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:11.000129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:10.380478Z","time spent":"619.564062ms","remote":"127.0.0.1:49236","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-06-30T18:51:11.077199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.60172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-30T18:51:11.077256Z","caller":"traceutil/trace.go:171","msg":"trace[588538826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24652; }","duration":"519.702182ms","start":"2026-06-30T18:51:10.557538Z","end":"2026-06-30T18:51:11.07724Z","steps":["trace[588538826] 'agreement among raft nodes before linearized reading' (duration: 519.543359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.077283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:10.557514Z","time spent":"519.763603ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T18:51:11.077496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.338619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-06-30T18:51:11.077524Z","caller":"traceutil/trace.go:171","msg":"trace[1239366411] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:24653; }","duration":"421.39493ms","start":"2026-06-30T18:51:10.656119Z","end":"2026-06-30T18:51:11.077514Z","steps":["trace[1239366411] 'agreement among raft nodes before linearized reading' (duration: 421.291118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.077544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:10.656103Z","time spent":"421.437081ms","remote":"127.0.0.1:49154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"warn","ts":"2026-06-30T18:51:11.077629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.617379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-06-30T18:51:11.077686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.676271ms","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-06-30T18:51:11.077706Z","caller":"traceutil/trace.go:171","msg":"trace[144459009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24653; }","duration":"443.736542ms","start":"2026-06-30T18:51:10.633964Z","end":"2026-06-30T18:51:11.0777Z","steps":["trace[144459009] 'agreement among raft nodes before linearized reading' (duration: 443.676331ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:11.077713Z","caller":"traceutil/trace.go:171","msg":"trace[1047539294] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:24653; }","duration":"237.763842ms","start":"2026-06-30T18:51:10.839932Z","end":"2026-06-30T18:51:11.077696Z","steps":["trace[1047539294] 'agreement among raft nodes before linearized reading' (duration: 237.448286ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:11.077694Z","caller":"traceutil/trace.go:171","msg":"trace[1923119548] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"445.264122ms","start":"2026-06-30T18:51:10.6324Z","end":"2026-06-30T18:51:11.077664Z","steps":["trace[1923119548] 'process raft request' (duration: 443.920686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.07789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.687219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:11.077919Z","caller":"traceutil/trace.go:171","msg":"trace[2016160123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24653; }","duration":"173.791051ms","start":"2026-06-30T18:51:10.904121Z","end":"2026-06-30T18:51:11.077912Z","steps":["trace[2016160123] 'agreement among raft nodes before linearized reading' (duration: 173.74167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.07791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:10.632368Z","time spent":"445.412745ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:11.07773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:10.633941Z","time spent":"443.782733ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-30T18:51:11.078014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.888097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T18:51:11.078105Z","caller":"traceutil/trace.go:171","msg":"trace[333819837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24653; }","duration":"516.988909ms","start":"2026-06-30T18:51:10.561096Z","end":"2026-06-30T18:51:11.078085Z","steps":["trace[333819837] 'agreement among raft nodes before linearized reading' (duration: 516.641362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.07815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:10.561074Z","time spent":"517.066281ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-30T18:51:11.078393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.671928ms","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":"warn","ts":"2026-06-30T18:51:11.078387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.051593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-30T18:51:11.078459Z","caller":"traceutil/trace.go:171","msg":"trace[580923510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24653; }","duration":"515.176785ms","start":"2026-06-30T18:51:10.563267Z","end":"2026-06-30T18:51:11.078443Z","steps":["trace[580923510] 'agreement among raft nodes before linearized reading' (duration: 515.013222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.0785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:10.563245Z","time spent":"515.247817ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-30T18:51:11.078462Z","caller":"traceutil/trace.go:171","msg":"trace[1562540028] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24653; }","duration":"511.75261ms","start":"2026-06-30T18:51:10.566688Z","end":"2026-06-30T18:51:11.07844Z","steps":["trace[1562540028] 'agreement among raft nodes before linearized reading' (duration: 511.511036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.078549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:10.566682Z","time spent":"511.854842ms","remote":"127.0.0.1:49236","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-06-30T18:51:11.664273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.547715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:11.664525Z","caller":"traceutil/trace.go:171","msg":"trace[1261528690] transaction","detail":"{read_only:false; response_revision:24654; number_of_response:1; }","duration":"580.902519ms","start":"2026-06-30T18:51:11.083579Z","end":"2026-06-30T18:51:11.664481Z","steps":["trace[1261528690] 'process raft request' (duration: 397.039278ms)","trace[1261528690] 'compare' (duration: 182.137019ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:11.664562Z","caller":"traceutil/trace.go:171","msg":"trace[1131555245] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"580.544413ms","start":"2026-06-30T18:51:11.084Z","end":"2026-06-30T18:51:11.664544Z","steps":["trace[1131555245] 'process raft request' (duration: 580.39502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.664647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:11.083992Z","time spent":"580.619255ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:11.664718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:11.08356Z","time spent":"581.049723ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:11.664863Z","caller":"traceutil/trace.go:171","msg":"trace[1067783892] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"580.689657ms","start":"2026-06-30T18:51:11.084142Z","end":"2026-06-30T18:51:11.664832Z","steps":["trace[1067783892] 'process raft request' (duration: 580.3546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.665011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:11.084123Z","time spent":"580.810619ms","remote":"127.0.0.1:49236","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-06-30T18:51:11.66534Z","caller":"traceutil/trace.go:171","msg":"trace[1205321034] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"580.331159ms","start":"2026-06-30T18:51:11.084993Z","end":"2026-06-30T18:51:11.665324Z","steps":["trace[1205321034] 'process raft request' (duration: 580.206347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.665434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:11.084969Z","time spent":"580.42061ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:11.665366Z","caller":"traceutil/trace.go:171","msg":"trace[2105845785] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"580.264868ms","start":"2026-06-30T18:51:11.084994Z","end":"2026-06-30T18:51:11.665259Z","steps":["trace[2105845785] 'process raft request' (duration: 579.781829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.666077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:11.084969Z","time spent":"580.633604ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:12.000536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.397948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:12.000796Z","caller":"traceutil/trace.go:171","msg":"trace[437946325] linearizableReadLoop","detail":"{readStateIndex:25460; appliedIndex:25454; }","duration":"812.344002ms","start":"2026-06-30T18:51:11.188429Z","end":"2026-06-30T18:51:12.000773Z","steps":["trace[437946325] 'read index received' (duration: 292.240554ms)","trace[437946325] 'applied index is now lower than readState.Index' (duration: 520.102468ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:12.000894Z","caller":"traceutil/trace.go:171","msg":"trace[266211596] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"811.6782ms","start":"2026-06-30T18:51:11.189205Z","end":"2026-06-30T18:51:12.000883Z","steps":["trace[266211596] 'process raft request' (duration: 811.448975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:12.000966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:11.189193Z","time spent":"811.736341ms","remote":"127.0.0.1:49236","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-06-30T18:51:12.001132Z","caller":"traceutil/trace.go:171","msg":"trace[310752713] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"908.657027ms","start":"2026-06-30T18:51:11.092406Z","end":"2026-06-30T18:51:12.001063Z","steps":["trace[310752713] 'process raft request' (duration: 682.584095ms)","trace[310752713] 'compare' (duration: 225.088422ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:12.001244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.779411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-30T18:51:12.001287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:11.092394Z","time spent":"908.810609ms","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:12.001335Z","caller":"traceutil/trace.go:171","msg":"trace[1663029700] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:24660; }","duration":"812.919854ms","start":"2026-06-30T18:51:11.188391Z","end":"2026-06-30T18:51:12.00131Z","steps":["trace[1663029700] 'agreement among raft nodes before linearized reading' (duration: 812.69398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:12.001393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:11.18837Z","time spent":"813.011866ms","remote":"127.0.0.1:49500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":60,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:51:12.027959Z","caller":"traceutil/trace.go:171","msg":"trace[1121647457] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"355.437191ms","start":"2026-06-30T18:51:11.672504Z","end":"2026-06-30T18:51:12.027941Z","steps":["trace[1121647457] 'process raft request' (duration: 355.269338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:12.028069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:11.672482Z","time spent":"355.539713ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:12.028257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.032166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:12.02835Z","caller":"traceutil/trace.go:171","msg":"trace[1461082135] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24661; }","duration":"455.180209ms","start":"2026-06-30T18:51:11.573149Z","end":"2026-06-30T18:51:12.028329Z","steps":["trace[1461082135] 'agreement among raft nodes before linearized reading' (duration: 454.866293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:12.028495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:11.573129Z","time spent":"455.348382ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:51:17.584132Z","caller":"traceutil/trace.go:171","msg":"trace[1706080730] linearizableReadLoop","detail":"{readStateIndex:25559; appliedIndex:25558; }","duration":"198.241184ms","start":"2026-06-30T18:51:17.385872Z","end":"2026-06-30T18:51:17.584113Z","steps":["trace[1706080730] 'read index received' (duration: 198.203843ms)","trace[1706080730] 'applied index is now lower than readState.Index' (duration: 36.541µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:17.58423Z","caller":"traceutil/trace.go:171","msg":"trace[139589102] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"214.178716ms","start":"2026-06-30T18:51:17.370022Z","end":"2026-06-30T18:51:17.5842Z","steps":["trace[139589102] 'process raft request' (duration: 213.973762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:17.584303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.415807ms","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":"info","ts":"2026-06-30T18:51:17.584329Z","caller":"traceutil/trace.go:171","msg":"trace[397000380] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24757; }","duration":"198.495448ms","start":"2026-06-30T18:51:17.385823Z","end":"2026-06-30T18:51:17.584319Z","steps":["trace[397000380] 'agreement among raft nodes before linearized reading' (duration: 198.377106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:17.753144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.746965ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-30T18:51:17.753306Z","caller":"traceutil/trace.go:171","msg":"trace[727732130] linearizableReadLoop","detail":"{readStateIndex:25560; appliedIndex:25559; }","duration":"169.115502ms","start":"2026-06-30T18:51:17.584178Z","end":"2026-06-30T18:51:17.753294Z","steps":["trace[727732130] 'read index received' (duration: 18.256945ms)","trace[727732130] 'applied index is now lower than readState.Index' (duration: 150.858037ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:17.753406Z","caller":"traceutil/trace.go:171","msg":"trace[171409741] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"164.316841ms","start":"2026-06-30T18:51:17.589065Z","end":"2026-06-30T18:51:17.753381Z","steps":["trace[171409741] 'process raft request' (duration: 164.156999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:17.866904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.683577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-30T18:51:17.866973Z","caller":"traceutil/trace.go:171","msg":"trace[970475929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24758; }","duration":"174.805821ms","start":"2026-06-30T18:51:17.692151Z","end":"2026-06-30T18:51:17.866957Z","steps":["trace[970475929] 'agreement among raft nodes before linearized reading' (duration: 61.23438ms)","trace[970475929] 'range keys from in-memory index tree' (duration: 113.382876ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:17.866974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.110015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-06-30T18:51:17.867074Z","caller":"traceutil/trace.go:171","msg":"trace[1435676378] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24758; }","duration":"214.259447ms","start":"2026-06-30T18:51:17.652788Z","end":"2026-06-30T18:51:17.867047Z","steps":["trace[1435676378] 'agreement among raft nodes before linearized reading' (duration: 100.586414ms)","trace[1435676378] 'range keys from in-memory index tree' (duration: 113.424948ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:17.867087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.472865ms","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":"warn","ts":"2026-06-30T18:51:17.867172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.556927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-30T18:51:17.867176Z","caller":"traceutil/trace.go:171","msg":"trace[1366823884] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24758; }","duration":"174.590337ms","start":"2026-06-30T18:51:17.692566Z","end":"2026-06-30T18:51:17.867156Z","steps":["trace[1366823884] 'agreement among raft nodes before linearized reading' (duration: 60.832413ms)","trace[1366823884] 'range keys from in-memory index tree' (duration: 113.570461ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:17.86721Z","caller":"traceutil/trace.go:171","msg":"trace[2100602613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24758; }","duration":"174.590147ms","start":"2026-06-30T18:51:17.692606Z","end":"2026-06-30T18:51:17.867196Z","steps":["trace[2100602613] 'agreement among raft nodes before linearized reading' (duration: 60.791222ms)","trace[2100602613] 'range keys from in-memory index tree' (duration: 113.551261ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:17.867087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.460691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T18:51:17.867271Z","caller":"traceutil/trace.go:171","msg":"trace[84215640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24758; }","duration":"177.671525ms","start":"2026-06-30T18:51:17.689589Z","end":"2026-06-30T18:51:17.867261Z","steps":["trace[84215640] 'agreement among raft nodes before linearized reading' (duration: 63.821069ms)","trace[84215640] 'range keys from in-memory index tree' (duration: 113.557851ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:17.867087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.679427ms","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-06-30T18:51:17.867342Z","caller":"traceutil/trace.go:171","msg":"trace[866899428] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24758; }","duration":"175.959703ms","start":"2026-06-30T18:51:17.691375Z","end":"2026-06-30T18:51:17.867335Z","steps":["trace[866899428] 'agreement among raft nodes before linearized reading' (duration: 62.011605ms)","trace[866899428] 'range keys from in-memory index tree' (duration: 113.579161ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:17.867423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.660027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-30T18:51:17.86749Z","caller":"traceutil/trace.go:171","msg":"trace[1406958352] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24758; }","duration":"429.770988ms","start":"2026-06-30T18:51:17.4377Z","end":"2026-06-30T18:51:17.86747Z","steps":["trace[1406958352] 'agreement among raft nodes before linearized reading' (duration: 315.644587ms)","trace[1406958352] 'range keys from in-memory index tree' (duration: 113.934998ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:17.86752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:17.437682Z","time spent":"429.8318ms","remote":"127.0.0.1:49094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-06-30T18:51:18.229741Z","caller":"traceutil/trace.go:171","msg":"trace[189355027] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"357.776265ms","start":"2026-06-30T18:51:17.871935Z","end":"2026-06-30T18:51:18.229711Z","steps":["trace[189355027] 'process raft request' (duration: 314.590498ms)","trace[189355027] 'compare' (duration: 42.870131ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:18.229746Z","caller":"traceutil/trace.go:171","msg":"trace[285951219] transaction","detail":"{read_only:false; response_revision:24761; number_of_response:1; }","duration":"357.51249ms","start":"2026-06-30T18:51:17.872204Z","end":"2026-06-30T18:51:18.229717Z","steps":["trace[285951219] 'process raft request' (duration: 357.384448ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:18.229835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.551672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-30T18:51:18.229858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:17.871898Z","time spent":"357.899947ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:18.229893Z","caller":"traceutil/trace.go:171","msg":"trace[923216240] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"357.506061ms","start":"2026-06-30T18:51:17.872378Z","end":"2026-06-30T18:51:18.229884Z","steps":["trace[923216240] 'process raft request' (duration: 357.282196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:18.229884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:17.872183Z","time spent":"357.627392ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:18.229866Z","caller":"traceutil/trace.go:171","msg":"trace[874027924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24763; }","duration":"327.611114ms","start":"2026-06-30T18:51:17.902248Z","end":"2026-06-30T18:51:18.229859Z","steps":["trace[874027924] 'agreement among raft nodes before linearized reading' (duration: 327.530863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:18.229961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:17.872366Z","time spent":"357.546851ms","remote":"127.0.0.1:49236","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-06-30T18:51:18.229737Z","caller":"traceutil/trace.go:171","msg":"trace[1538783554] linearizableReadLoop","detail":"{readStateIndex:25566; appliedIndex:25561; }","duration":"327.45051ms","start":"2026-06-30T18:51:17.902268Z","end":"2026-06-30T18:51:18.229718Z","steps":["trace[1538783554] 'read index received' (duration: 2.073919ms)","trace[1538783554] 'applied index is now lower than readState.Index' (duration: 325.375831ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:18.230012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:17.902233Z","time spent":"327.751066ms","remote":"127.0.0.1:40116","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-06-30T18:51:18.230049Z","caller":"traceutil/trace.go:171","msg":"trace[1143724758] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"357.183074ms","start":"2026-06-30T18:51:17.87286Z","end":"2026-06-30T18:51:18.230043Z","steps":["trace[1143724758] 'process raft request' (duration: 356.830907ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:18.23019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:17.872848Z","time spent":"357.261185ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:18.25271Z","caller":"traceutil/trace.go:171","msg":"trace[1441855651] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"231.221518ms","start":"2026-06-30T18:51:18.021444Z","end":"2026-06-30T18:51:18.252665Z","steps":["trace[1441855651] 'process raft request' (duration: 231.015814ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:18.252864Z","caller":"traceutil/trace.go:171","msg":"trace[627366248] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"201.944344ms","start":"2026-06-30T18:51:18.050913Z","end":"2026-06-30T18:51:18.252857Z","steps":["trace[627366248] 'process raft request' (duration: 201.71348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:18.253025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.476163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f8d61b60-f1a6-4b5b-9b5c-df38fa5abe03\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-30T18:51:18.2531Z","caller":"traceutil/trace.go:171","msg":"trace[2030619571] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f8d61b60-f1a6-4b5b-9b5c-df38fa5abe03; range_end:; response_count:1; response_revision:24765; }","duration":"334.606965ms","start":"2026-06-30T18:51:17.918474Z","end":"2026-06-30T18:51:18.253081Z","steps":["trace[2030619571] 'agreement among raft nodes before linearized reading' (duration: 334.385411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:18.253132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:17.918456Z","time spent":"334.667787ms","remote":"127.0.0.1:49166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f8d61b60-f1a6-4b5b-9b5c-df38fa5abe03\" "} {"level":"info","ts":"2026-06-30T18:51:18.484167Z","caller":"traceutil/trace.go:171","msg":"trace[382700743] linearizableReadLoop","detail":"{readStateIndex:25569; appliedIndex:25568; }","duration":"222.018013ms","start":"2026-06-30T18:51:18.26212Z","end":"2026-06-30T18:51:18.484138Z","steps":["trace[382700743] 'read index received' (duration: 221.557675ms)","trace[382700743] 'applied index is now lower than readState.Index' (duration: 459.208µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:18.484319Z","caller":"traceutil/trace.go:171","msg":"trace[557961881] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"223.90707ms","start":"2026-06-30T18:51:18.260377Z","end":"2026-06-30T18:51:18.484284Z","steps":["trace[557961881] 'process raft request' (duration: 223.38278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:18.484481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.334439ms","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-06-30T18:51:18.484521Z","caller":"traceutil/trace.go:171","msg":"trace[952451832] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24766; }","duration":"222.404521ms","start":"2026-06-30T18:51:18.262103Z","end":"2026-06-30T18:51:18.484507Z","steps":["trace[952451832] 'agreement among raft nodes before linearized reading' (duration: 222.188467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:18.484667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.6175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f8d61b60-f1a6-4b5b-9b5c-df38fa5abe03\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-06-30T18:51:18.484765Z","caller":"traceutil/trace.go:171","msg":"trace[168180728] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f8d61b60-f1a6-4b5b-9b5c-df38fa5abe03; range_end:; response_count:1; response_revision:24766; }","duration":"218.746302ms","start":"2026-06-30T18:51:18.265996Z","end":"2026-06-30T18:51:18.484742Z","steps":["trace[168180728] 'agreement among raft nodes before linearized reading' (duration: 218.553168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:18.484814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.329268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:18.484883Z","caller":"traceutil/trace.go:171","msg":"trace[1072290465] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:24766; }","duration":"186.44654ms","start":"2026-06-30T18:51:18.298419Z","end":"2026-06-30T18:51:18.484865Z","steps":["trace[1072290465] 'agreement among raft nodes before linearized reading' (duration: 186.341518ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:20.387496Z","caller":"traceutil/trace.go:171","msg":"trace[1338901493] transaction","detail":"{read_only:false; response_revision:24781; number_of_response:1; }","duration":"148.857448ms","start":"2026-06-30T18:51:20.238622Z","end":"2026-06-30T18:51:20.387479Z","steps":["trace[1338901493] 'process raft request' (duration: 148.687415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:20.387637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.17214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:20.387689Z","caller":"traceutil/trace.go:171","msg":"trace[1150469293] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:24783; }","duration":"106.267433ms","start":"2026-06-30T18:51:20.2814Z","end":"2026-06-30T18:51:20.387668Z","steps":["trace[1150469293] 'agreement among raft nodes before linearized reading' (duration: 106.12211ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:20.387471Z","caller":"traceutil/trace.go:171","msg":"trace[367759949] linearizableReadLoop","detail":"{readStateIndex:25586; appliedIndex:25580; }","duration":"105.996637ms","start":"2026-06-30T18:51:20.281435Z","end":"2026-06-30T18:51:20.387431Z","steps":["trace[367759949] 'read index received' (duration: 53.775929ms)","trace[367759949] 'applied index is now lower than readState.Index' (duration: 52.219448ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:20.387755Z","caller":"traceutil/trace.go:171","msg":"trace[1666267531] transaction","detail":"{read_only:false; response_revision:24780; number_of_response:1; }","duration":"149.105093ms","start":"2026-06-30T18:51:20.238607Z","end":"2026-06-30T18:51:20.387712Z","steps":["trace[1666267531] 'process raft request' (duration: 148.572153ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:20.387844Z","caller":"traceutil/trace.go:171","msg":"trace[766851046] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"128.984073ms","start":"2026-06-30T18:51:20.258835Z","end":"2026-06-30T18:51:20.387819Z","steps":["trace[766851046] 'process raft request' (duration: 128.552055ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:20.387967Z","caller":"traceutil/trace.go:171","msg":"trace[288412139] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"129.41963ms","start":"2026-06-30T18:51:20.258537Z","end":"2026-06-30T18:51:20.387957Z","steps":["trace[288412139] 'process raft request' (duration: 128.816499ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:24.258107Z","caller":"traceutil/trace.go:171","msg":"trace[59494584] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"204.994122ms","start":"2026-06-30T18:51:24.053083Z","end":"2026-06-30T18:51:24.258077Z","steps":["trace[59494584] 'process raft request' (duration: 204.412721ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:35.62768Z","caller":"traceutil/trace.go:171","msg":"trace[71723631] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"485.334731ms","start":"2026-06-30T18:51:35.142311Z","end":"2026-06-30T18:51:35.627646Z","steps":["trace[71723631] 'process raft request' (duration: 485.26127ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:35.627887Z","caller":"traceutil/trace.go:171","msg":"trace[804989725] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"487.993282ms","start":"2026-06-30T18:51:35.139844Z","end":"2026-06-30T18:51:35.627837Z","steps":["trace[804989725] 'process raft request' (duration: 406.154151ms)","trace[804989725] 'compare' (duration: 81.395231ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:35.627954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:35.142288Z","time spent":"485.504164ms","remote":"127.0.0.1:49236","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-06-30T18:51:35.628112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:35.139813Z","time spent":"488.199435ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:35.645742Z","caller":"traceutil/trace.go:171","msg":"trace[962366580] linearizableReadLoop","detail":"{readStateIndex:25733; appliedIndex:25730; }","duration":"452.688553ms","start":"2026-06-30T18:51:35.19302Z","end":"2026-06-30T18:51:35.645708Z","steps":["trace[962366580] 'read index received' (duration: 352.981754ms)","trace[962366580] 'applied index is now lower than readState.Index' (duration: 99.705379ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:35.645971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.930787ms","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-06-30T18:51:35.646006Z","caller":"traceutil/trace.go:171","msg":"trace[614436981] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24927; }","duration":"453.021429ms","start":"2026-06-30T18:51:35.192973Z","end":"2026-06-30T18:51:35.645994Z","steps":["trace[614436981] 'agreement among raft nodes before linearized reading' (duration: 452.841686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:35.646038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:35.192948Z","time spent":"453.08223ms","remote":"127.0.0.1:49236","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":"info","ts":"2026-06-30T18:51:35.646409Z","caller":"traceutil/trace.go:171","msg":"trace[1247786523] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"483.2681ms","start":"2026-06-30T18:51:35.163124Z","end":"2026-06-30T18:51:35.646392Z","steps":["trace[1247786523] 'process raft request' (duration: 482.380974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:35.646503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:35.1631Z","time spent":"483.336492ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:35.646637Z","caller":"traceutil/trace.go:171","msg":"trace[1278419405] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"231.654726ms","start":"2026-06-30T18:51:35.414972Z","end":"2026-06-30T18:51:35.646626Z","steps":["trace[1278419405] 'process raft request' (duration: 230.685398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.2332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.697893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:36.233307Z","caller":"traceutil/trace.go:171","msg":"trace[740909179] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"582.050882ms","start":"2026-06-30T18:51:35.651237Z","end":"2026-06-30T18:51:36.233288Z","steps":["trace[740909179] 'process raft request' (duration: 336.194517ms)","trace[740909179] 'compare' (duration: 245.608261ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:36.233362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:35.651216Z","time spent":"582.118843ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:36.867374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.101971ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-30T18:51:36.867688Z","caller":"traceutil/trace.go:171","msg":"trace[1427444448] linearizableReadLoop","detail":"{readStateIndex:25736; appliedIndex:25735; }","duration":"805.749117ms","start":"2026-06-30T18:51:36.061916Z","end":"2026-06-30T18:51:36.867665Z","steps":["trace[1427444448] 'read index received' (duration: 203.279149ms)","trace[1427444448] 'applied index is now lower than readState.Index' (duration: 602.468408ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:36.86799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.057953ms","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-06-30T18:51:36.868046Z","caller":"traceutil/trace.go:171","msg":"trace[444513149] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24928; }","duration":"806.157945ms","start":"2026-06-30T18:51:36.061869Z","end":"2026-06-30T18:51:36.868027Z","steps":["trace[444513149] 'agreement among raft nodes before linearized reading' (duration: 805.915801ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:36.868032Z","caller":"traceutil/trace.go:171","msg":"trace[1837267784] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"427.465494ms","start":"2026-06-30T18:51:36.440504Z","end":"2026-06-30T18:51:36.867969Z","steps":["trace[1837267784] 'process raft request' (duration: 427.022416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.868278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.440489Z","time spent":"427.667618ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:36.868095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.061851Z","time spent":"806.234296ms","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3259,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-06-30T18:51:37.147192Z","caller":"traceutil/trace.go:171","msg":"trace[1534027333] transaction","detail":"{read_only:false; response_revision:24931; number_of_response:1; }","duration":"492.633128ms","start":"2026-06-30T18:51:36.654528Z","end":"2026-06-30T18:51:37.147161Z","steps":["trace[1534027333] 'process raft request' (duration: 492.450935ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:37.147343Z","caller":"traceutil/trace.go:171","msg":"trace[426164544] linearizableReadLoop","detail":"{readStateIndex:25740; appliedIndex:25737; }","duration":"279.560435ms","start":"2026-06-30T18:51:36.867721Z","end":"2026-06-30T18:51:37.147281Z","steps":["trace[426164544] 'read index received' (duration: 227.420576ms)","trace[426164544] 'applied index is now lower than readState.Index' (duration: 52.110188ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:37.147423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.654508Z","time spent":"492.780322ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:37.147599Z","caller":"traceutil/trace.go:171","msg":"trace[776619510] transaction","detail":"{read_only:false; response_revision:24930; number_of_response:1; }","duration":"700.082937ms","start":"2026-06-30T18:51:36.447501Z","end":"2026-06-30T18:51:37.147584Z","steps":["trace[776619510] 'process raft request' (duration: 647.608503ms)","trace[776619510] 'compare' (duration: 51.642929ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:37.14767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.447476Z","time spent":"700.160008ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:37.147763Z","caller":"traceutil/trace.go:171","msg":"trace[2080431655] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"438.073725ms","start":"2026-06-30T18:51:36.709667Z","end":"2026-06-30T18:51:37.14774Z","steps":["trace[2080431655] 'process raft request' (duration: 437.444323ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:37.148012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.709645Z","time spent":"438.184697ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:37.148422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.290962ms","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:1936"} {"level":"warn","ts":"2026-06-30T18:51:37.148436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074602298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-06-30T18:51:37.14844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.04532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T18:51:37.148482Z","caller":"traceutil/trace.go:171","msg":"trace[1098860120] 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:24932; }","duration":"425.364023ms","start":"2026-06-30T18:51:36.723103Z","end":"2026-06-30T18:51:37.148467Z","steps":["trace[1098860120] 'agreement among raft nodes before linearized reading' (duration: 425.16297ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:37.148501Z","caller":"traceutil/trace.go:171","msg":"trace[408157105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24932; }","duration":"1.074663769s","start":"2026-06-30T18:51:36.073818Z","end":"2026-06-30T18:51:37.148482Z","steps":["trace[408157105] 'agreement among raft nodes before linearized reading' (duration: 1.074466675s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:37.148514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.723083Z","time spent":"425.424335ms","remote":"127.0.0.1:49154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-06-30T18:51:37.148511Z","caller":"traceutil/trace.go:171","msg":"trace[909805486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24932; }","duration":"734.164352ms","start":"2026-06-30T18:51:36.414327Z","end":"2026-06-30T18:51:37.148492Z","steps":["trace[909805486] 'agreement among raft nodes before linearized reading' (duration: 733.918607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:37.148501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.529742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-30T18:51:37.14855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.414308Z","time spent":"734.236134ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-30T18:51:37.148589Z","caller":"traceutil/trace.go:171","msg":"trace[225541398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24932; }","duration":"684.686625ms","start":"2026-06-30T18:51:36.46388Z","end":"2026-06-30T18:51:37.148567Z","steps":["trace[225541398] 'agreement among raft nodes before linearized reading' (duration: 684.370569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:37.148611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.906266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-30T18:51:37.148635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.463863Z","time spent":"684.764086ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-30T18:51:37.148651Z","caller":"traceutil/trace.go:171","msg":"trace[2001984570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24932; }","duration":"245.963667ms","start":"2026-06-30T18:51:36.902673Z","end":"2026-06-30T18:51:37.148637Z","steps":["trace[2001984570] 'agreement among raft nodes before linearized reading' (duration: 245.904436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:37.148716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.706474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-06-30T18:51:37.148538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.073766Z","time spent":"1.074763501s","remote":"127.0.0.1:49236","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":"info","ts":"2026-06-30T18:51:37.148802Z","caller":"traceutil/trace.go:171","msg":"trace[2068710392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24932; }","duration":"686.884027ms","start":"2026-06-30T18:51:36.461895Z","end":"2026-06-30T18:51:37.148779Z","steps":["trace[2068710392] 'agreement among raft nodes before linearized reading' (duration: 686.613391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:37.148858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.461878Z","time spent":"686.965889ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-30T18:51:37.148994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.754852ms","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-06-30T18:51:37.149048Z","caller":"traceutil/trace.go:171","msg":"trace[1538971515] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24932; }","duration":"726.825254ms","start":"2026-06-30T18:51:36.422207Z","end":"2026-06-30T18:51:37.149032Z","steps":["trace[1538971515] 'agreement among raft nodes before linearized reading' (duration: 726.669861ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:37.149083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.422197Z","time spent":"726.879675ms","remote":"127.0.0.1:49236","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-06-30T18:51:37.424399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.775581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:37.424513Z","caller":"traceutil/trace.go:171","msg":"trace[132925655] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"270.246158ms","start":"2026-06-30T18:51:37.154247Z","end":"2026-06-30T18:51:37.424493Z","steps":["trace[132925655] 'process raft request' (duration: 115.274673ms)","trace[132925655] 'compare' (duration: 154.059568ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:37.424963Z","caller":"traceutil/trace.go:171","msg":"trace[2127837955] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"269.33322ms","start":"2026-06-30T18:51:37.155604Z","end":"2026-06-30T18:51:37.424937Z","steps":["trace[2127837955] 'process raft request' (duration: 269.224508ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:37.425097Z","caller":"traceutil/trace.go:171","msg":"trace[436778018] transaction","detail":"{read_only:false; response_revision:24936; number_of_response:1; }","duration":"269.122087ms","start":"2026-06-30T18:51:37.155956Z","end":"2026-06-30T18:51:37.425078Z","steps":["trace[436778018] 'process raft request' (duration: 268.910553ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:37.425105Z","caller":"traceutil/trace.go:171","msg":"trace[613748571] transaction","detail":"{read_only:false; response_revision:24934; number_of_response:1; }","duration":"269.549834ms","start":"2026-06-30T18:51:37.155535Z","end":"2026-06-30T18:51:37.425085Z","steps":["trace[613748571] 'process raft request' (duration: 269.224578ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:37.425405Z","caller":"traceutil/trace.go:171","msg":"trace[490421427] transaction","detail":"{read_only:false; response_revision:24937; number_of_response:1; }","duration":"268.404382ms","start":"2026-06-30T18:51:37.156988Z","end":"2026-06-30T18:51:37.425392Z","steps":["trace[490421427] 'process raft request' (duration: 268.027945ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:37.425397Z","caller":"traceutil/trace.go:171","msg":"trace[1953336022] linearizableReadLoop","detail":"{readStateIndex:25745; appliedIndex:25740; }","duration":"267.696069ms","start":"2026-06-30T18:51:37.157673Z","end":"2026-06-30T18:51:37.425369Z","steps":["trace[1953336022] 'read index received' (duration: 112.098313ms)","trace[1953336022] 'applied index is now lower than readState.Index' (duration: 155.595936ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:37.425407Z","caller":"traceutil/trace.go:171","msg":"trace[1104095349] transaction","detail":"{read_only:false; response_revision:24938; number_of_response:1; }","duration":"264.09726ms","start":"2026-06-30T18:51:37.161298Z","end":"2026-06-30T18:51:37.425395Z","steps":["trace[1104095349] 'process raft request' (duration: 263.965598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:37.425595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.895323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:37.425634Z","caller":"traceutil/trace.go:171","msg":"trace[1348256808] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24938; }","duration":"267.964134ms","start":"2026-06-30T18:51:37.157658Z","end":"2026-06-30T18:51:37.425622Z","steps":["trace[1348256808] 'agreement among raft nodes before linearized reading' (duration: 267.854872ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:39.06906Z","caller":"traceutil/trace.go:171","msg":"trace[912671197] linearizableReadLoop","detail":"{readStateIndex:25754; appliedIndex:25752; }","duration":"158.748777ms","start":"2026-06-30T18:51:38.91028Z","end":"2026-06-30T18:51:39.069029Z","steps":["trace[912671197] 'read index received' (duration: 5.671487ms)","trace[912671197] 'applied index is now lower than readState.Index' (duration: 153.075939ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:39.069421Z","caller":"traceutil/trace.go:171","msg":"trace[546391654] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"195.682626ms","start":"2026-06-30T18:51:38.873693Z","end":"2026-06-30T18:51:39.069376Z","steps":["trace[546391654] 'process raft request' (duration: 161.847175ms)","trace[546391654] 'compare' (duration: 33.321681ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:39.069919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.607482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:39.070042Z","caller":"traceutil/trace.go:171","msg":"trace[2012427625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24945; }","duration":"159.780596ms","start":"2026-06-30T18:51:38.910236Z","end":"2026-06-30T18:51:39.070017Z","steps":["trace[2012427625] 'agreement among raft nodes before linearized reading' (duration: 158.974011ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:39.270914Z","caller":"traceutil/trace.go:171","msg":"trace[85404577] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"119.047764ms","start":"2026-06-30T18:51:39.151843Z","end":"2026-06-30T18:51:39.270891Z","steps":["trace[85404577] 'process raft request' (duration: 101.95556ms)","trace[85404577] 'compare' (duration: 16.495883ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:39.270934Z","caller":"traceutil/trace.go:171","msg":"trace[985115087] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"115.461046ms","start":"2026-06-30T18:51:39.155447Z","end":"2026-06-30T18:51:39.270908Z","steps":["trace[985115087] 'process raft request' (duration: 115.14973ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:41.438412Z","caller":"traceutil/trace.go:171","msg":"trace[1000614670] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"160.204804ms","start":"2026-06-30T18:51:41.278174Z","end":"2026-06-30T18:51:41.438379Z","steps":["trace[1000614670] 'process raft request' (duration: 135.570157ms)","trace[1000614670] 'compare' (duration: 24.371942ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:42.923834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.414944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:42.923918Z","caller":"traceutil/trace.go:171","msg":"trace[1129488540] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:24981; }","duration":"107.562347ms","start":"2026-06-30T18:51:42.816326Z","end":"2026-06-30T18:51:42.923889Z","steps":["trace[1129488540] 'count revisions from in-memory index tree' (duration: 107.283301ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:50.764963Z","caller":"traceutil/trace.go:171","msg":"trace[1261505456] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"107.561536ms","start":"2026-06-30T18:51:50.657361Z","end":"2026-06-30T18:51:50.764923Z","steps":["trace[1261505456] 'process raft request' (duration: 107.348683ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:56.03789Z","caller":"traceutil/trace.go:171","msg":"trace[493939769] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"326.973942ms","start":"2026-06-30T18:51:55.710891Z","end":"2026-06-30T18:51:56.037865Z","steps":["trace[493939769] 'process raft request' (duration: 326.784718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:56.038051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:55.710865Z","time spent":"327.099214ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:56.038652Z","caller":"traceutil/trace.go:171","msg":"trace[1774461560] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"325.909283ms","start":"2026-06-30T18:51:55.712713Z","end":"2026-06-30T18:51:56.038622Z","steps":["trace[1774461560] 'process raft request' (duration: 325.684049ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:56.038719Z","caller":"traceutil/trace.go:171","msg":"trace[1775195724] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"323.740042ms","start":"2026-06-30T18:51:55.714946Z","end":"2026-06-30T18:51:56.038686Z","steps":["trace[1775195724] 'process raft request' (duration: 323.599829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:56.038778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:55.712687Z","time spent":"326.036666ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:56.038859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:55.714926Z","time spent":"323.859954ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:56.626277Z","caller":"traceutil/trace.go:171","msg":"trace[1676099246] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"169.467918ms","start":"2026-06-30T18:51:56.456788Z","end":"2026-06-30T18:51:56.626256Z","steps":["trace[1676099246] 'process raft request' (duration: 169.332086ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:57.821286Z","caller":"traceutil/trace.go:171","msg":"trace[1786697680] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"152.10894ms","start":"2026-06-30T18:51:57.669145Z","end":"2026-06-30T18:51:57.821254Z","steps":["trace[1786697680] 'process raft request' (duration: 151.368627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:52:00.459429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.034649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:52:00.459558Z","caller":"traceutil/trace.go:171","msg":"trace[627207696] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"406.376196ms","start":"2026-06-30T18:52:00.053167Z","end":"2026-06-30T18:52:00.459544Z","steps":["trace[627207696] 'process raft request' (duration: 406.331365ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:52:00.459607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:52:00.053142Z","time spent":"406.444917ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:52:00.459602Z","caller":"traceutil/trace.go:171","msg":"trace[900667001] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"406.526889ms","start":"2026-06-30T18:52:00.053045Z","end":"2026-06-30T18:52:00.459572Z","steps":["trace[900667001] 'process raft request' (duration: 244.283986ms)","trace[900667001] 'compare' (duration: 161.910287ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:52:00.459878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:52:00.053028Z","time spent":"406.63589ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:52:00.761722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.891067ms","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-06-30T18:52:00.761809Z","caller":"traceutil/trace.go:171","msg":"trace[1184803619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25114; }","duration":"123.01823ms","start":"2026-06-30T18:52:00.638772Z","end":"2026-06-30T18:52:00.76179Z","steps":["trace[1184803619] 'range keys from in-memory index tree' (duration: 122.700684ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:52:22.392597Z","caller":"traceutil/trace.go:171","msg":"trace[1954674770] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"152.937566ms","start":"2026-06-30T18:52:22.239624Z","end":"2026-06-30T18:52:22.392561Z","steps":["trace[1954674770] 'process raft request' (duration: 152.723782ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:52:31.999389Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000668d.wal"} {"level":"info","ts":"2026-06-30T18:53:37.604983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23216} {"level":"info","ts":"2026-06-30T18:53:37.739089Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23216,"took":"131.263646ms","hash":411399186,"current-db-size-bytes":58036224,"current-db-size":"58 MB","current-db-size-in-use-bytes":36220928,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-30T18:53:37.739163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":411399186,"revision":23216,"compact-revision":20009} {"level":"info","ts":"2026-06-30T18:56:03.17742Z","caller":"traceutil/trace.go:171","msg":"trace[1718023564] transaction","detail":"{read_only:false; response_revision:27558; number_of_response:1; }","duration":"115.449748ms","start":"2026-06-30T18:56:03.061945Z","end":"2026-06-30T18:56:03.177395Z","steps":["trace[1718023564] 'process raft request' (duration: 115.286155ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:58:37.61357Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26064} {"level":"info","ts":"2026-06-30T18:58:37.75034Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26064,"took":"132.313016ms","hash":2912128043,"current-db-size-bytes":58036224,"current-db-size":"58 MB","current-db-size-in-use-bytes":36921344,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-06-30T18:58:37.750413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2912128043,"revision":26064,"compact-revision":23216} {"level":"info","ts":"2026-06-30T18:59:29.137499Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T18:59:29.144487Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-30T18:59:29.144601Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-30T19:03:37.621683Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28702} {"level":"info","ts":"2026-06-30T19:03:37.750042Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28702,"took":"124.539041ms","hash":1072527358,"current-db-size-bytes":58036224,"current-db-size":"58 MB","current-db-size-in-use-bytes":31469568,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-30T19:03:37.750112Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1072527358,"revision":28702,"compact-revision":26064} {"level":"info","ts":"2026-06-30T19:08:37.629896Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30992} {"level":"info","ts":"2026-06-30T19:08:37.753792Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30992,"took":"117.310955ms","hash":3197973516,"current-db-size-bytes":58036224,"current-db-size":"58 MB","current-db-size-in-use-bytes":32735232,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-30T19:08:37.753862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3197973516,"revision":30992,"compact-revision":28702} {"level":"info","ts":"2026-06-30T19:13:37.636148Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33289} {"level":"info","ts":"2026-06-30T19:13:37.79963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33289,"took":"151.250151ms","hash":4091270491,"current-db-size-bytes":60346368,"current-db-size":"60 MB","current-db-size-in-use-bytes":32370688,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-30T19:13:37.799734Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4091270491,"revision":33289,"compact-revision":30992} {"level":"info","ts":"2026-06-30T19:18:37.642169Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35585} {"level":"info","ts":"2026-06-30T19:18:37.781989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35585,"took":"135.604805ms","hash":2343048707,"current-db-size-bytes":61235200,"current-db-size":"61 MB","current-db-size-in-use-bytes":31756288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-30T19:18:37.782062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2343048707,"revision":35585,"compact-revision":33289} {"level":"info","ts":"2026-06-30T19:20:40.57377Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T19:20:40.579149Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-30T19:20:40.579352Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}