{"level":"warn","ts":"2026-08-26T00:20:52.332202Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:20:52.332322Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.69: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://162.253.55.69:2380","--initial-cluster=instance=https://162.253.55.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.69:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-26T00:20:52.332404Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:20:52.332418Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.69:2380"]} {"level":"info","ts":"2026-08-26T00:20:52.332442Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-26T00:20:52.333075Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.69:2379"]} {"level":"info","ts":"2026-08-26T00:20:52.33317Z","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://162.253.55.69:2380"],"listen-peer-urls":["https://162.253.55.69:2380"],"advertise-client-urls":["https://162.253.55.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.69:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-26T00:20:52.335928Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.49372ms"} {"level":"info","ts":"2026-08-26T00:20:52.34046Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"905ccde8a677f143","cluster-id":"6fd07fe3af558460"} {"level":"info","ts":"2026-08-26T00:20:52.340614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 switched to configuration voters=()"} {"level":"info","ts":"2026-08-26T00:20:52.340655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became follower at term 0"} {"level":"info","ts":"2026-08-26T00:20:52.340679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 905ccde8a677f143 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-26T00:20:52.340701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became follower at term 1"} {"level":"info","ts":"2026-08-26T00:20:52.340773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 switched to configuration voters=(10402415638427988291)"} {"level":"warn","ts":"2026-08-26T00:20:52.346743Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-26T00:20:52.348962Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-26T00:20:52.350348Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-26T00:20:52.353193Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"905ccde8a677f143","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-26T00:20:52.353579Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"905ccde8a677f143","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-26T00:20:52.353594Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-26T00:20:52.353699Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-26T00:20:52.353713Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-26T00:20:52.357538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 switched to configuration voters=(10402415638427988291)"} {"level":"info","ts":"2026-08-26T00:20:52.362356Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6fd07fe3af558460","local-member-id":"905ccde8a677f143","added-peer-id":"905ccde8a677f143","added-peer-peer-urls":["https://162.253.55.69:2380"]} {"level":"info","ts":"2026-08-26T00:20:52.363704Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-26T00:20:52.363856Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.69:2380"} {"level":"info","ts":"2026-08-26T00:20:52.363955Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.69:2380"} {"level":"info","ts":"2026-08-26T00:20:52.364074Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"905ccde8a677f143","initial-advertise-peer-urls":["https://162.253.55.69:2380"],"listen-peer-urls":["https://162.253.55.69:2380"],"advertise-client-urls":["https://162.253.55.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-26T00:20:52.36414Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-26T00:20:52.641129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 is starting a new election at term 1"} {"level":"info","ts":"2026-08-26T00:20:52.641219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-26T00:20:52.641272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 received MsgPreVoteResp from 905ccde8a677f143 at term 1"} {"level":"info","ts":"2026-08-26T00:20:52.641294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became candidate at term 2"} {"level":"info","ts":"2026-08-26T00:20:52.641317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 received MsgVoteResp from 905ccde8a677f143 at term 2"} {"level":"info","ts":"2026-08-26T00:20:52.641334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became leader at term 2"} {"level":"info","ts":"2026-08-26T00:20:52.641354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 905ccde8a677f143 elected leader 905ccde8a677f143 at term 2"} {"level":"info","ts":"2026-08-26T00:20:52.642404Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"905ccde8a677f143","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.69:2379]}","request-path":"/0/members/905ccde8a677f143/attributes","cluster-id":"6fd07fe3af558460","publish-timeout":"7s"} {"level":"info","ts":"2026-08-26T00:20:52.642708Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:20:52.643076Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:20:52.64305Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:20:52.64339Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-26T00:20:52.643433Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-26T00:20:52.643583Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6fd07fe3af558460","local-member-id":"905ccde8a677f143","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:20:52.643668Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:20:52.643697Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:20:52.647118Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.69:2379"} {"level":"info","ts":"2026-08-26T00:20:52.648286Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-26T00:21:12.750198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.544201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:21:12.750367Z","caller":"traceutil/trace.go:171","msg":"trace[267037968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:378; }","duration":"192.767116ms","start":"2026-08-26T00:21:12.557576Z","end":"2026-08-26T00:21:12.750343Z","steps":["trace[267037968] 'range keys from in-memory index tree' (duration: 192.477109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:29.127647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.352445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:21:29.127749Z","caller":"traceutil/trace.go:171","msg":"trace[1557457378] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:481; }","duration":"117.516479ms","start":"2026-08-26T00:21:29.01021Z","end":"2026-08-26T00:21:29.127727Z","steps":["trace[1557457378] 'range keys from in-memory index tree' (duration: 117.208461ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:35.439162Z","caller":"traceutil/trace.go:171","msg":"trace[251429023] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"169.115264ms","start":"2026-08-26T00:21:35.269996Z","end":"2026-08-26T00:21:35.439111Z","steps":["trace[251429023] 'process raft request' (duration: 164.124364ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:36.072243Z","caller":"traceutil/trace.go:171","msg":"trace[24913265] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"279.612997ms","start":"2026-08-26T00:21:35.792588Z","end":"2026-08-26T00:21:36.072201Z","steps":["trace[24913265] 'read index received' (duration: 255.217215ms)","trace[24913265] 'applied index is now lower than readState.Index' (duration: 24.393372ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:21:36.072281Z","caller":"traceutil/trace.go:171","msg":"trace[2064661687] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"463.584227ms","start":"2026-08-26T00:21:35.608662Z","end":"2026-08-26T00:21:36.072246Z","steps":["trace[2064661687] 'process raft request' (duration: 439.256287ms)","trace[2064661687] 'compare' (duration: 24.020022ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:21:36.0725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.881865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.69\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-26T00:21:36.072596Z","caller":"traceutil/trace.go:171","msg":"trace[1032288372] range","detail":"{range_begin:/registry/masterleases/162.253.55.69; range_end:; response_count:1; response_revision:590; }","duration":"280.03341ms","start":"2026-08-26T00:21:35.792545Z","end":"2026-08-26T00:21:36.072578Z","steps":["trace[1032288372] 'agreement among raft nodes before linearized reading' (duration: 279.829194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:36.07307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:21:35.60863Z","time spent":"463.755922ms","remote":"127.0.0.1:60460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:21:50.625016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.70426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-26T00:21:50.625103Z","caller":"traceutil/trace.go:171","msg":"trace[1023302311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"104.842364ms","start":"2026-08-26T00:21:50.520245Z","end":"2026-08-26T00:21:50.625088Z","steps":["trace[1023302311] 'range keys from in-memory index tree' (duration: 104.546226ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:23:01.360707Z","caller":"traceutil/trace.go:171","msg":"trace[1512270899] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"402.419057ms","start":"2026-08-26T00:23:00.958252Z","end":"2026-08-26T00:23:01.360671Z","steps":["trace[1512270899] 'process raft request' (duration: 361.289403ms)","trace[1512270899] 'compare' (duration: 40.856846ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:23:01.360766Z","caller":"traceutil/trace.go:171","msg":"trace[485567381] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"385.652661ms","start":"2026-08-26T00:23:00.975061Z","end":"2026-08-26T00:23:01.360713Z","steps":["trace[485567381] 'process raft request' (duration: 385.503717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:23:01.360889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:23:00.95824Z","time spent":"402.563591ms","remote":"127.0.0.1:60460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:23:01.36102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:23:00.975023Z","time spent":"385.846396ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:23:01.430384Z","caller":"traceutil/trace.go:171","msg":"trace[859146237] linearizableReadLoop","detail":"{readStateIndex:1850; appliedIndex:1847; }","duration":"351.595783ms","start":"2026-08-26T00:23:01.07876Z","end":"2026-08-26T00:23:01.430355Z","steps":["trace[859146237] 'read index received' (duration: 240.790911ms)","trace[859146237] 'applied index is now lower than readState.Index' (duration: 110.803712ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:23:01.430661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.411613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18cf331da5eafae1\" ","response":"range_response_count:1 size:982"} {"level":"warn","ts":"2026-08-26T00:23:01.430701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.938302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-26T00:23:01.430744Z","caller":"traceutil/trace.go:171","msg":"trace[1319897132] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18cf331da5eafae1; range_end:; response_count:1; response_revision:1784; }","duration":"289.532886ms","start":"2026-08-26T00:23:01.141193Z","end":"2026-08-26T00:23:01.430726Z","steps":["trace[1319897132] 'agreement among raft nodes before linearized reading' (duration: 289.340111ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:23:01.430764Z","caller":"traceutil/trace.go:171","msg":"trace[422375583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1784; }","duration":"352.038275ms","start":"2026-08-26T00:23:01.07871Z","end":"2026-08-26T00:23:01.430749Z","steps":["trace[422375583] 'agreement among raft nodes before linearized reading' (duration: 351.771548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:23:01.430811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:23:01.078688Z","time spent":"352.114127ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-26T00:23:01.430661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.429889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-26T00:23:01.430915Z","caller":"traceutil/trace.go:171","msg":"trace[2033204355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1784; }","duration":"346.711857ms","start":"2026-08-26T00:23:01.084187Z","end":"2026-08-26T00:23:01.430899Z","steps":["trace[2033204355] 'agreement among raft nodes before linearized reading' (duration: 346.326156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:23:01.43097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:23:01.084177Z","time spent":"346.776969ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-26T00:23:01.73847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.059723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2258da2e-ba9a-4455-af78-dd299d86f965\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-08-26T00:23:01.738562Z","caller":"traceutil/trace.go:171","msg":"trace[463522641] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2258da2e-ba9a-4455-af78-dd299d86f965; range_end:; response_count:1; response_revision:1788; }","duration":"174.196547ms","start":"2026-08-26T00:23:01.564344Z","end":"2026-08-26T00:23:01.738541Z","steps":["trace[463522641] 'range keys from in-memory index tree' (duration: 173.819697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:23:01.738563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.815157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-26T00:23:01.738601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.690649ms","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-08-26T00:23:01.73875Z","caller":"traceutil/trace.go:171","msg":"trace[195213059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1788; }","duration":"161.903146ms","start":"2026-08-26T00:23:01.576824Z","end":"2026-08-26T00:23:01.738727Z","steps":["trace[195213059] 'range keys from in-memory index tree' (duration: 161.419851ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:23:01.738657Z","caller":"traceutil/trace.go:171","msg":"trace[755217422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1788; }","duration":"163.936431ms","start":"2026-08-26T00:23:01.574696Z","end":"2026-08-26T00:23:01.738632Z","steps":["trace[755217422] 'range keys from in-memory index tree' (duration: 163.651304ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:23:46.037429Z","caller":"traceutil/trace.go:171","msg":"trace[311488768] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"107.119621ms","start":"2026-08-26T00:23:45.930271Z","end":"2026-08-26T00:23:46.037391Z","steps":["trace[311488768] 'process raft request' (duration: 100.982195ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:23:46.516675Z","caller":"traceutil/trace.go:171","msg":"trace[1087655909] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"148.949928ms","start":"2026-08-26T00:23:46.36768Z","end":"2026-08-26T00:23:46.51663Z","steps":["trace[1087655909] 'process raft request' (duration: 148.716492ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:25:15.06249Z","caller":"traceutil/trace.go:171","msg":"trace[287039523] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"106.47466ms","start":"2026-08-26T00:25:14.955991Z","end":"2026-08-26T00:25:15.062466Z","steps":["trace[287039523] 'process raft request' (duration: 106.342757ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:27:56.608057Z","caller":"traceutil/trace.go:171","msg":"trace[665085836] transaction","detail":"{read_only:false; response_revision:3809; number_of_response:1; }","duration":"181.445642ms","start":"2026-08-26T00:27:56.426526Z","end":"2026-08-26T00:27:56.607972Z","steps":["trace[665085836] 'process raft request' (duration: 119.078232ms)","trace[665085836] 'compare' (duration: 62.186495ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:27:56.626348Z","caller":"traceutil/trace.go:171","msg":"trace[235422687] transaction","detail":"{read_only:false; response_revision:3810; number_of_response:1; }","duration":"142.545227ms","start":"2026-08-26T00:27:56.483747Z","end":"2026-08-26T00:27:56.626293Z","steps":["trace[235422687] 'process raft request' (duration: 142.025782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:20.483879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.373932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-edfdfb6a-4be2-47b7-b240-a41453167423\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-08-26T00:29:20.48396Z","caller":"traceutil/trace.go:171","msg":"trace[1513099434] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-edfdfb6a-4be2-47b7-b240-a41453167423; range_end:; response_count:1; response_revision:4796; }","duration":"108.543508ms","start":"2026-08-26T00:29:20.375396Z","end":"2026-08-26T00:29:20.48394Z","steps":["trace[1513099434] 'range keys from in-memory index tree' (duration: 108.242609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:25.961014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.188024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:29:25.961485Z","caller":"traceutil/trace.go:171","msg":"trace[435872100] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"218.411243ms","start":"2026-08-26T00:29:25.743013Z","end":"2026-08-26T00:29:25.961424Z","steps":["trace[435872100] 'process raft request' (duration: 112.448307ms)","trace[435872100] 'compare' (duration: 104.929997ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:25.961551Z","caller":"traceutil/trace.go:171","msg":"trace[502055953] linearizableReadLoop","detail":"{readStateIndex:5182; appliedIndex:5180; }","duration":"189.934489ms","start":"2026-08-26T00:29:25.771597Z","end":"2026-08-26T00:29:25.961532Z","steps":["trace[502055953] 'read index received' (duration: 83.902831ms)","trace[502055953] 'applied index is now lower than readState.Index' (duration: 106.030348ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:25.9617Z","caller":"traceutil/trace.go:171","msg":"trace[1796437693] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"215.888123ms","start":"2026-08-26T00:29:25.745778Z","end":"2026-08-26T00:29:25.961666Z","steps":["trace[1796437693] 'process raft request' (duration: 215.512232ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:25.961731Z","caller":"traceutil/trace.go:171","msg":"trace[253658143] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"133.206766ms","start":"2026-08-26T00:29:25.828483Z","end":"2026-08-26T00:29:25.96169Z","steps":["trace[253658143] 'process raft request' (duration: 132.98348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:25.961789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.190256ms","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-08-26T00:29:25.961827Z","caller":"traceutil/trace.go:171","msg":"trace[934823939] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4997; }","duration":"190.268028ms","start":"2026-08-26T00:29:25.771547Z","end":"2026-08-26T00:29:25.961815Z","steps":["trace[934823939] 'agreement among raft nodes before linearized reading' (duration: 190.131854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:25.962013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.966902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-26T00:29:25.962061Z","caller":"traceutil/trace.go:171","msg":"trace[1617160346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4997; }","duration":"127.048444ms","start":"2026-08-26T00:29:25.835Z","end":"2026-08-26T00:29:25.962049Z","steps":["trace[1617160346] 'agreement among raft nodes before linearized reading' (duration: 126.88124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:25.962032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.224043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9f2fcece-18c2-4446-86e2-d5bdba018f25\" ","response":"range_response_count:1 size:3600"} {"level":"warn","ts":"2026-08-26T00:29:25.962115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.655282ms","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-08-26T00:29:25.962132Z","caller":"traceutil/trace.go:171","msg":"trace[1724439596] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9f2fcece-18c2-4446-86e2-d5bdba018f25; range_end:; response_count:1; response_revision:4997; }","duration":"168.393678ms","start":"2026-08-26T00:29:25.793712Z","end":"2026-08-26T00:29:25.962106Z","steps":["trace[1724439596] 'agreement among raft nodes before linearized reading' (duration: 168.11587ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:25.962166Z","caller":"traceutil/trace.go:171","msg":"trace[1110970967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4997; }","duration":"122.800476ms","start":"2026-08-26T00:29:25.839353Z","end":"2026-08-26T00:29:25.962154Z","steps":["trace[1110970967] 'agreement among raft nodes before linearized reading' (duration: 122.505448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:25.962176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.292091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.69\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-26T00:29:25.962296Z","caller":"traceutil/trace.go:171","msg":"trace[589410655] range","detail":"{range_begin:/registry/masterleases/162.253.55.69; range_end:; response_count:1; response_revision:4997; }","duration":"141.484337ms","start":"2026-08-26T00:29:25.820793Z","end":"2026-08-26T00:29:25.962278Z","steps":["trace[589410655] 'agreement among raft nodes before linearized reading' (duration: 141.133347ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:28.197418Z","caller":"traceutil/trace.go:171","msg":"trace[138931254] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"173.265133ms","start":"2026-08-26T00:29:28.024128Z","end":"2026-08-26T00:29:28.197393Z","steps":["trace[138931254] 'process raft request' (duration: 131.355854ms)","trace[138931254] 'compare' (duration: 41.767025ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:28.203756Z","caller":"traceutil/trace.go:171","msg":"trace[863413680] linearizableReadLoop","detail":"{readStateIndex:5234; appliedIndex:5231; }","duration":"173.726527ms","start":"2026-08-26T00:29:28.029933Z","end":"2026-08-26T00:29:28.20366Z","steps":["trace[863413680] 'read index received' (duration: 125.505902ms)","trace[863413680] 'applied index is now lower than readState.Index' (duration: 48.217495ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:28.204498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.538619ms","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-08-26T00:29:28.204509Z","caller":"traceutil/trace.go:171","msg":"trace[2033026676] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"177.869271ms","start":"2026-08-26T00:29:28.026568Z","end":"2026-08-26T00:29:28.204437Z","steps":["trace[2033026676] 'process raft request' (duration: 176.638607ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:28.20459Z","caller":"traceutil/trace.go:171","msg":"trace[619807611] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5047; }","duration":"174.645321ms","start":"2026-08-26T00:29:28.029913Z","end":"2026-08-26T00:29:28.204558Z","steps":["trace[619807611] 'agreement among raft nodes before linearized reading' (duration: 174.391574ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:28.204536Z","caller":"traceutil/trace.go:171","msg":"trace[1147807366] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"177.213534ms","start":"2026-08-26T00:29:28.027247Z","end":"2026-08-26T00:29:28.204461Z","steps":["trace[1147807366] 'process raft request' (duration: 176.33344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:34.223013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.791986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-08-26T00:29:34.223165Z","caller":"traceutil/trace.go:171","msg":"trace[1603839684] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5185; }","duration":"104.008981ms","start":"2026-08-26T00:29:34.119126Z","end":"2026-08-26T00:29:34.223135Z","steps":["trace[1603839684] 'agreement among raft nodes before linearized reading' (duration: 103.5769ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:39.449043Z","caller":"traceutil/trace.go:171","msg":"trace[1506036599] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"345.978643ms","start":"2026-08-26T00:29:39.103031Z","end":"2026-08-26T00:29:39.44901Z","steps":["trace[1506036599] 'process raft request' (duration: 269.66385ms)","trace[1506036599] 'compare' (duration: 75.908792ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:39.44918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:39.102997Z","time spent":"346.114867ms","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:29:39.449226Z","caller":"traceutil/trace.go:171","msg":"trace[108525986] linearizableReadLoop","detail":"{readStateIndex:5460; appliedIndex:5458; }","duration":"341.715475ms","start":"2026-08-26T00:29:39.107465Z","end":"2026-08-26T00:29:39.44918Z","steps":["trace[108525986] 'read index received' (duration: 14.890214ms)","trace[108525986] 'applied index is now lower than readState.Index' (duration: 326.822191ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:39.449492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.006404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-77nxg\" ","response":"range_response_count:1 size:4991"} {"level":"info","ts":"2026-08-26T00:29:39.449527Z","caller":"traceutil/trace.go:171","msg":"trace[1596574454] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-77nxg; range_end:; response_count:1; response_revision:5266; }","duration":"342.081776ms","start":"2026-08-26T00:29:39.107436Z","end":"2026-08-26T00:29:39.449518Z","steps":["trace[1596574454] 'agreement among raft nodes before linearized reading' (duration: 341.864719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:39.449556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:39.107421Z","time spent":"342.128418ms","remote":"127.0.0.1:60460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":5014,"request content":"key:\"/registry/pods/monitoring/goldpinger-77nxg\" "} {"level":"warn","ts":"2026-08-26T00:29:48.176074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.587754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:29:48.176315Z","caller":"traceutil/trace.go:171","msg":"trace[2107029342] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"322.143521ms","start":"2026-08-26T00:29:47.854149Z","end":"2026-08-26T00:29:48.176292Z","steps":["trace[2107029342] 'process raft request' (duration: 201.206377ms)","trace[2107029342] 'compare' (duration: 120.427329ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:48.176528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:47.854117Z","time spent":"322.354407ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:29:49.322137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.26141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:29:49.322285Z","caller":"traceutil/trace.go:171","msg":"trace[1094237407] linearizableReadLoop","detail":"{readStateIndex:5676; appliedIndex:5675; }","duration":"1.069119628s","start":"2026-08-26T00:29:48.253145Z","end":"2026-08-26T00:29:49.322265Z","steps":["trace[1094237407] 'read index received' (duration: 378.685763ms)","trace[1094237407] 'applied index is now lower than readState.Index' (duration: 690.431885ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:49.322398Z","caller":"traceutil/trace.go:171","msg":"trace[73642555] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"1.105824209s","start":"2026-08-26T00:29:48.216548Z","end":"2026-08-26T00:29:49.322373Z","steps":["trace[73642555] 'process raft request' (duration: 415.21846ms)","trace[73642555] 'compare' (duration: 689.494227ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:49.322502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:48.216514Z","time spent":"1.105943842s","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:29:49.322539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069393025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-7s9gp\" ","response":"range_response_count:1 size:8066"} {"level":"info","ts":"2026-08-26T00:29:49.322641Z","caller":"traceutil/trace.go:171","msg":"trace[1266462378] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-7s9gp; range_end:; response_count:1; response_revision:5477; }","duration":"1.069528989s","start":"2026-08-26T00:29:48.253092Z","end":"2026-08-26T00:29:49.322621Z","steps":["trace[1266462378] 'agreement among raft nodes before linearized reading' (duration: 1.069274912s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:49.322709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:48.253073Z","time spent":"1.069620022s","remote":"127.0.0.1:60460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":8089,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-7s9gp\" "} {"level":"warn","ts":"2026-08-26T00:29:50.143378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.009353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:29:50.143562Z","caller":"traceutil/trace.go:171","msg":"trace[1831204481] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"992.855466ms","start":"2026-08-26T00:29:49.150684Z","end":"2026-08-26T00:29:50.14354Z","steps":["trace[1831204481] 'process raft request' (duration: 421.587456ms)","trace[1831204481] 'compare' (duration: 570.756386ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:50.143668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:49.15066Z","time spent":"992.97209ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:29:50.148969Z","caller":"traceutil/trace.go:171","msg":"trace[1622125019] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"908.577912ms","start":"2026-08-26T00:29:49.240294Z","end":"2026-08-26T00:29:50.148872Z","steps":["trace[1622125019] 'process raft request' (duration: 908.359606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.149166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:49.240262Z","time spent":"908.805799ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:29:50.149331Z","caller":"traceutil/trace.go:171","msg":"trace[547581136] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"817.123009ms","start":"2026-08-26T00:29:49.332169Z","end":"2026-08-26T00:29:50.149292Z","steps":["trace[547581136] 'process raft request' (duration: 816.628485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.149541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:49.332141Z","time spent":"817.290674ms","remote":"127.0.0.1:60460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8455,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:29:50.149733Z","caller":"traceutil/trace.go:171","msg":"trace[868140326] transaction","detail":"{read_only:false; response_revision:5482; number_of_response:1; }","duration":"647.458419ms","start":"2026-08-26T00:29:49.502244Z","end":"2026-08-26T00:29:50.149702Z","steps":["trace[868140326] 'process raft request' (duration: 647.294395ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:50.149725Z","caller":"traceutil/trace.go:171","msg":"trace[1013528666] linearizableReadLoop","detail":"{readStateIndex:5678; appliedIndex:5676; }","duration":"827.354593ms","start":"2026-08-26T00:29:49.322332Z","end":"2026-08-26T00:29:50.149687Z","steps":["trace[1013528666] 'read index received' (duration: 249.907781ms)","trace[1013528666] 'applied index is now lower than readState.Index' (duration: 577.445282ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:50.149852Z","caller":"traceutil/trace.go:171","msg":"trace[1610944599] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"652.957183ms","start":"2026-08-26T00:29:49.496871Z","end":"2026-08-26T00:29:50.149829Z","steps":["trace[1610944599] 'process raft request' (duration: 652.310265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.149956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:49.496841Z","time spent":"653.064125ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:29:50.149975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.573661202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-08-26T00:29:50.149944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.743883176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-26T00:29:50.150002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.503418297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-26T00:29:50.15002Z","caller":"traceutil/trace.go:171","msg":"trace[1354708910] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5482; }","duration":"1.573748484s","start":"2026-08-26T00:29:48.576258Z","end":"2026-08-26T00:29:50.150007Z","steps":["trace[1354708910] 'agreement among raft nodes before linearized reading' (duration: 1.57358329s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.150061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:48.576234Z","time spent":"1.573819926s","remote":"127.0.0.1:60460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-26T00:29:50.150063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.821355851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-26T00:29:50.150061Z","caller":"traceutil/trace.go:171","msg":"trace[594360031] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5482; }","duration":"1.744074241s","start":"2026-08-26T00:29:48.405958Z","end":"2026-08-26T00:29:50.150033Z","steps":["trace[594360031] 'agreement among raft nodes before linearized reading' (duration: 1.743874166s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:50.150106Z","caller":"traceutil/trace.go:171","msg":"trace[55211343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5482; }","duration":"1.821439423s","start":"2026-08-26T00:29:48.328655Z","end":"2026-08-26T00:29:50.150094Z","steps":["trace[55211343] 'agreement among raft nodes before linearized reading' (duration: 1.821236417s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.150123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:48.405933Z","time spent":"1.744176134s","remote":"127.0.0.1:39586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:29:50.150142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370823039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7ee073c9-8a93-4c8e-9e71-0226e14ad567\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-26T00:29:50.150167Z","caller":"traceutil/trace.go:171","msg":"trace[1807518794] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7ee073c9-8a93-4c8e-9e71-0226e14ad567; range_end:; response_count:1; response_revision:5482; }","duration":"1.370881511s","start":"2026-08-26T00:29:48.779277Z","end":"2026-08-26T00:29:50.150159Z","steps":["trace[1807518794] 'agreement among raft nodes before linearized reading' (duration: 1.370808309s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.150159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154928084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7qh7b\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-08-26T00:29:50.150202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:48.779258Z","time spent":"1.370939213s","remote":"127.0.0.1:60460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7ee073c9-8a93-4c8e-9e71-0226e14ad567\" "} {"level":"info","ts":"2026-08-26T00:29:50.150224Z","caller":"traceutil/trace.go:171","msg":"trace[1007071983] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7qh7b; range_end:; response_count:1; response_revision:5482; }","duration":"1.155043617s","start":"2026-08-26T00:29:48.99516Z","end":"2026-08-26T00:29:50.150203Z","steps":["trace[1007071983] 'agreement among raft nodes before linearized reading' (duration: 1.154851382s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.150248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.822384839s","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":"warn","ts":"2026-08-26T00:29:50.150263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:48.995138Z","time spent":"1.155117659s","remote":"127.0.0.1:60460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-7qh7b\" "} {"level":"info","ts":"2026-08-26T00:29:50.150061Z","caller":"traceutil/trace.go:171","msg":"trace[1641961704] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5482; }","duration":"1.503507579s","start":"2026-08-26T00:29:48.646531Z","end":"2026-08-26T00:29:50.150039Z","steps":["trace[1641961704] 'agreement among raft nodes before linearized reading' (duration: 1.503227712s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:50.150287Z","caller":"traceutil/trace.go:171","msg":"trace[1034495247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5482; }","duration":"1.822470532s","start":"2026-08-26T00:29:48.327806Z","end":"2026-08-26T00:29:50.150277Z","steps":["trace[1034495247] 'agreement among raft nodes before linearized reading' (duration: 1.822380339s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.150161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:48.328648Z","time spent":"1.821503255s","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-26T00:29:50.150324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:48.327781Z","time spent":"1.822537273s","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-26T00:29:50.150349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:48.646512Z","time spent":"1.503804038s","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-26T00:29:50.150381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.569435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-26T00:29:50.150418Z","caller":"traceutil/trace.go:171","msg":"trace[399659562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5482; }","duration":"371.645296ms","start":"2026-08-26T00:29:49.778761Z","end":"2026-08-26T00:29:50.150406Z","steps":["trace[399659562] 'agreement among raft nodes before linearized reading' (duration: 371.550684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.150426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.118072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-26T00:29:50.150451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:49.778735Z","time spent":"371.708949ms","remote":"127.0.0.1:60548","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-08-26T00:29:50.150286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.64131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:29:50.150457Z","caller":"traceutil/trace.go:171","msg":"trace[1471129669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5482; }","duration":"385.188014ms","start":"2026-08-26T00:29:49.76526Z","end":"2026-08-26T00:29:50.150448Z","steps":["trace[1471129669] 'agreement among raft nodes before linearized reading' (duration: 385.102201ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.150491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:49.765236Z","time spent":"385.248606ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-26T00:29:50.150493Z","caller":"traceutil/trace.go:171","msg":"trace[996945294] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5482; }","duration":"736.885277ms","start":"2026-08-26T00:29:49.413595Z","end":"2026-08-26T00:29:50.15048Z","steps":["trace[996945294] 'agreement among raft nodes before linearized reading' (duration: 736.63958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.150539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:49.413574Z","time spent":"736.952289ms","remote":"127.0.0.1:60760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":21,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:29:50.150545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.959928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:29:50.150573Z","caller":"traceutil/trace.go:171","msg":"trace[179365520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5482; }","duration":"595.02022ms","start":"2026-08-26T00:29:49.555544Z","end":"2026-08-26T00:29:50.150564Z","steps":["trace[179365520] 'agreement among raft nodes before linearized reading' (duration: 594.977949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.150611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:49.555527Z","time spent":"595.078682ms","remote":"127.0.0.1:60270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:29:50.150751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:49.502215Z","time spent":"647.579453ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:29:50.488007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.704386ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:29:50.488357Z","caller":"traceutil/trace.go:171","msg":"trace[631686373] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5482; }","duration":"236.067866ms","start":"2026-08-26T00:29:50.25227Z","end":"2026-08-26T00:29:50.488338Z","steps":["trace[631686373] 'range keys from in-memory index tree' (duration: 235.682846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.488545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.817281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:29:50.489026Z","caller":"traceutil/trace.go:171","msg":"trace[837522768] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"331.410757ms","start":"2026-08-26T00:29:50.157585Z","end":"2026-08-26T00:29:50.488996Z","steps":["trace[837522768] 'process raft request' (duration: 331.294584ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:50.48901Z","caller":"traceutil/trace.go:171","msg":"trace[1472101051] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"331.515751ms","start":"2026-08-26T00:29:50.157465Z","end":"2026-08-26T00:29:50.488981Z","steps":["trace[1472101051] 'process raft request' (duration: 331.251434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.489322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:50.157576Z","time spent":"331.696646ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:29:50.489378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:50.157428Z","time spent":"331.85012ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:29:50.488995Z","caller":"traceutil/trace.go:171","msg":"trace[943076365] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"332.444496ms","start":"2026-08-26T00:29:50.156456Z","end":"2026-08-26T00:29:50.4889Z","steps":["trace[943076365] 'process raft request' (duration: 179.128631ms)","trace[943076365] 'compare' (duration: 152.258316ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:50.489479Z","caller":"traceutil/trace.go:171","msg":"trace[1634322136] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"331.815909ms","start":"2026-08-26T00:29:50.157641Z","end":"2026-08-26T00:29:50.489457Z","steps":["trace[1634322136] 'process raft request' (duration: 331.295965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.489563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:50.156433Z","time spent":"333.032322ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:29:50.489597Z","caller":"traceutil/trace.go:171","msg":"trace[1520875109] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"330.449341ms","start":"2026-08-26T00:29:50.159122Z","end":"2026-08-26T00:29:50.489572Z","steps":["trace[1520875109] 'process raft request' (duration: 330.205515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.489621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:50.15763Z","time spent":"331.907171ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:29:50.489709Z","caller":"traceutil/trace.go:171","msg":"trace[1577463177] linearizableReadLoop","detail":"{readStateIndex:5687; appliedIndex:5681; }","duration":"259.110686ms","start":"2026-08-26T00:29:50.230579Z","end":"2026-08-26T00:29:50.48969Z","steps":["trace[1577463177] 'read index received' (duration: 105.01848ms)","trace[1577463177] 'applied index is now lower than readState.Index' (duration: 154.091016ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:50.489742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:50.159113Z","time spent":"330.549975ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:29:50.489746Z","caller":"traceutil/trace.go:171","msg":"trace[1541609940] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"326.82433ms","start":"2026-08-26T00:29:50.162904Z","end":"2026-08-26T00:29:50.489728Z","steps":["trace[1541609940] 'process raft request' (duration: 326.560972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.489834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:50.162878Z","time spent":"326.905923ms","remote":"127.0.0.1:60460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:29:50.489846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.271792ms","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-08-26T00:29:50.489898Z","caller":"traceutil/trace.go:171","msg":"trace[211069399] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5488; }","duration":"259.374455ms","start":"2026-08-26T00:29:50.230508Z","end":"2026-08-26T00:29:50.489882Z","steps":["trace[211069399] 'agreement among raft nodes before linearized reading' (duration: 259.285882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.731751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.326099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:29:50.732099Z","caller":"traceutil/trace.go:171","msg":"trace[760982593] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"235.967613ms","start":"2026-08-26T00:29:50.496107Z","end":"2026-08-26T00:29:50.732075Z","steps":["trace[760982593] 'process raft request' (duration: 235.780278ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:50.732094Z","caller":"traceutil/trace.go:171","msg":"trace[1708936665] transaction","detail":"{read_only:false; response_revision:5490; number_of_response:1; }","duration":"236.708834ms","start":"2026-08-26T00:29:50.495299Z","end":"2026-08-26T00:29:50.732007Z","steps":["trace[1708936665] 'process raft request' (duration: 98.016346ms)","trace[1708936665] 'compare' (duration: 138.148823ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:50.732312Z","caller":"traceutil/trace.go:171","msg":"trace[1483949469] transaction","detail":"{read_only:false; response_revision:5492; number_of_response:1; }","duration":"235.799398ms","start":"2026-08-26T00:29:50.496478Z","end":"2026-08-26T00:29:50.732277Z","steps":["trace[1483949469] 'process raft request' (duration: 235.50266ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:50.73258Z","caller":"traceutil/trace.go:171","msg":"trace[1579022590] transaction","detail":"{read_only:false; response_revision:5493; number_of_response:1; }","duration":"232.339232ms","start":"2026-08-26T00:29:50.500223Z","end":"2026-08-26T00:29:50.732562Z","steps":["trace[1579022590] 'process raft request' (duration: 232.125176ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:50.732699Z","caller":"traceutil/trace.go:171","msg":"trace[1602881936] linearizableReadLoop","detail":"{readStateIndex:5692; appliedIndex:5688; }","duration":"226.268763ms","start":"2026-08-26T00:29:50.506409Z","end":"2026-08-26T00:29:50.732678Z","steps":["trace[1602881936] 'read index received' (duration: 86.918537ms)","trace[1602881936] 'applied index is now lower than readState.Index' (duration: 139.348116ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:50.732708Z","caller":"traceutil/trace.go:171","msg":"trace[1687779860] transaction","detail":"{read_only:false; response_revision:5494; number_of_response:1; }","duration":"218.056895ms","start":"2026-08-26T00:29:50.514566Z","end":"2026-08-26T00:29:50.732623Z","steps":["trace[1687779860] 'process raft request' (duration: 217.930081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:50.732923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.567945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:29:50.732943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.838844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-26T00:29:50.733018Z","caller":"traceutil/trace.go:171","msg":"trace[1569697849] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5494; }","duration":"233.936866ms","start":"2026-08-26T00:29:50.499067Z","end":"2026-08-26T00:29:50.733004Z","steps":["trace[1569697849] 'agreement among raft nodes before linearized reading' (duration: 233.738861ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:50.733034Z","caller":"traceutil/trace.go:171","msg":"trace[946454057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5494; }","duration":"174.693559ms","start":"2026-08-26T00:29:50.558295Z","end":"2026-08-26T00:29:50.732989Z","steps":["trace[946454057] 'agreement among raft nodes before linearized reading' (duration: 174.574176ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:50.921936Z","caller":"traceutil/trace.go:171","msg":"trace[1152015316] linearizableReadLoop","detail":"{readStateIndex:5694; appliedIndex:5693; }","duration":"176.083977ms","start":"2026-08-26T00:29:50.745825Z","end":"2026-08-26T00:29:50.921909Z","steps":["trace[1152015316] 'read index received' (duration: 112.074747ms)","trace[1152015316] 'applied index is now lower than readState.Index' (duration: 64.00801ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:50.922062Z","caller":"traceutil/trace.go:171","msg":"trace[1651207187] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"177.778476ms","start":"2026-08-26T00:29:50.744248Z","end":"2026-08-26T00:29:50.922027Z","steps":["trace[1651207187] 'process raft request' (duration: 113.747835ms)","trace[1651207187] 'compare' (duration: 63.777024ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:50.922196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.316635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-08-26T00:29:50.92227Z","caller":"traceutil/trace.go:171","msg":"trace[286191672] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5495; }","duration":"176.465449ms","start":"2026-08-26T00:29:50.745787Z","end":"2026-08-26T00:29:50.922253Z","steps":["trace[286191672] 'agreement among raft nodes before linearized reading' (duration: 176.224822ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:52.625973Z","caller":"traceutil/trace.go:171","msg":"trace[1159761551] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"126.505219ms","start":"2026-08-26T00:29:52.499442Z","end":"2026-08-26T00:29:52.625947Z","steps":["trace[1159761551] 'process raft request' (duration: 126.401046ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:52.626016Z","caller":"traceutil/trace.go:171","msg":"trace[177340235] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"126.646773ms","start":"2026-08-26T00:29:52.499319Z","end":"2026-08-26T00:29:52.625966Z","steps":["trace[177340235] 'process raft request' (duration: 86.391924ms)","trace[177340235] 'compare' (duration: 39.985841ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:52.626268Z","caller":"traceutil/trace.go:171","msg":"trace[140872272] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"126.010615ms","start":"2026-08-26T00:29:52.500237Z","end":"2026-08-26T00:29:52.626247Z","steps":["trace[140872272] 'process raft request' (duration: 125.659556ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:52.626467Z","caller":"traceutil/trace.go:171","msg":"trace[1800256646] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"125.535372ms","start":"2026-08-26T00:29:52.500907Z","end":"2026-08-26T00:29:52.626443Z","steps":["trace[1800256646] 'process raft request' (duration: 125.256734ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:53.409872Z","caller":"traceutil/trace.go:171","msg":"trace[2011406234] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"103.045517ms","start":"2026-08-26T00:29:53.306799Z","end":"2026-08-26T00:29:53.409844Z","steps":["trace[2011406234] 'process raft request' (duration: 102.878932ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:53.957987Z","caller":"traceutil/trace.go:171","msg":"trace[2050805105] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"162.670556ms","start":"2026-08-26T00:29:53.795284Z","end":"2026-08-26T00:29:53.957955Z","steps":["trace[2050805105] 'process raft request' (duration: 104.847397ms)","trace[2050805105] 'compare' (duration: 57.564881ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:53.958422Z","caller":"traceutil/trace.go:171","msg":"trace[1488034541] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"163.036396ms","start":"2026-08-26T00:29:53.795356Z","end":"2026-08-26T00:29:53.958392Z","steps":["trace[1488034541] 'process raft request' (duration: 162.528342ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:53.958626Z","caller":"traceutil/trace.go:171","msg":"trace[1616508756] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"163.184158ms","start":"2026-08-26T00:29:53.795416Z","end":"2026-08-26T00:29:53.9586Z","steps":["trace[1616508756] 'process raft request' (duration: 162.86319ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:53.958618Z","caller":"traceutil/trace.go:171","msg":"trace[725245136] linearizableReadLoop","detail":"{readStateIndex:5714; appliedIndex:5710; }","duration":"134.147192ms","start":"2026-08-26T00:29:53.824424Z","end":"2026-08-26T00:29:53.958571Z","steps":["trace[725245136] 'read index received' (duration: 75.554302ms)","trace[725245136] 'applied index is now lower than readState.Index' (duration: 58.59138ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:53.958864Z","caller":"traceutil/trace.go:171","msg":"trace[2065444110] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"161.886843ms","start":"2026-08-26T00:29:53.796958Z","end":"2026-08-26T00:29:53.958845Z","steps":["trace[2065444110] 'process raft request' (duration: 161.509063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:53.958947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.492831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:29:53.959022Z","caller":"traceutil/trace.go:171","msg":"trace[1696715524] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:5514; }","duration":"134.632844ms","start":"2026-08-26T00:29:53.82437Z","end":"2026-08-26T00:29:53.959003Z","steps":["trace[1696715524] 'agreement among raft nodes before linearized reading' (duration: 134.432409ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:54.141404Z","caller":"traceutil/trace.go:171","msg":"trace[19175365] linearizableReadLoop","detail":"{readStateIndex:5716; appliedIndex:5715; }","duration":"116.789069ms","start":"2026-08-26T00:29:54.024578Z","end":"2026-08-26T00:29:54.141367Z","steps":["trace[19175365] 'read index received' (duration: 114.023672ms)","trace[19175365] 'applied index is now lower than readState.Index' (duration: 2.763527ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:54.141564Z","caller":"traceutil/trace.go:171","msg":"trace[1253838340] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"172.727725ms","start":"2026-08-26T00:29:53.968823Z","end":"2026-08-26T00:29:54.14155Z","steps":["trace[1253838340] 'process raft request' (duration: 170.02973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:54.141688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.078547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-08-26T00:29:54.141793Z","caller":"traceutil/trace.go:171","msg":"trace[471675032] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:5516; }","duration":"117.234401ms","start":"2026-08-26T00:29:54.024533Z","end":"2026-08-26T00:29:54.141767Z","steps":["trace[471675032] 'agreement among raft nodes before linearized reading' (duration: 117.026475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:54.141809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.922811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:131020"} {"level":"info","ts":"2026-08-26T00:29:54.141904Z","caller":"traceutil/trace.go:171","msg":"trace[1008675525] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5516; }","duration":"113.053504ms","start":"2026-08-26T00:29:54.028827Z","end":"2026-08-26T00:29:54.141881Z","steps":["trace[1008675525] 'agreement among raft nodes before linearized reading' (duration: 112.742016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:54.389039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.866764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:29:54.389425Z","caller":"traceutil/trace.go:171","msg":"trace[412755245] linearizableReadLoop","detail":"{readStateIndex:5719; appliedIndex:5716; }","duration":"237.971269ms","start":"2026-08-26T00:29:54.15143Z","end":"2026-08-26T00:29:54.389401Z","steps":["trace[412755245] 'read index received' (duration: 22.937888ms)","trace[412755245] 'applied index is now lower than readState.Index' (duration: 215.031941ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:54.389449Z","caller":"traceutil/trace.go:171","msg":"trace[161078638] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"220.140884ms","start":"2026-08-26T00:29:54.169281Z","end":"2026-08-26T00:29:54.389421Z","steps":["trace[161078638] 'process raft request' (duration: 219.989159ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:54.389415Z","caller":"traceutil/trace.go:171","msg":"trace[1189114259] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"240.407047ms","start":"2026-08-26T00:29:54.148951Z","end":"2026-08-26T00:29:54.389358Z","steps":["trace[1189114259] 'process raft request' (duration: 97.106151ms)","trace[1189114259] 'compare' (duration: 142.700519ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:54.389437Z","caller":"traceutil/trace.go:171","msg":"trace[1373969759] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"239.486552ms","start":"2026-08-26T00:29:54.14991Z","end":"2026-08-26T00:29:54.389396Z","steps":["trace[1373969759] 'process raft request' (duration: 239.265215ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:54.389787Z","caller":"traceutil/trace.go:171","msg":"trace[1584242404] transaction","detail":"{read_only:false; response_revision:5521; number_of_response:1; }","duration":"159.289701ms","start":"2026-08-26T00:29:54.230466Z","end":"2026-08-26T00:29:54.389756Z","steps":["trace[1584242404] 'process raft request' (duration: 158.88992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:54.389843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.390141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-08-26T00:29:54.390006Z","caller":"traceutil/trace.go:171","msg":"trace[163797155] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:5521; }","duration":"238.565065ms","start":"2026-08-26T00:29:54.151407Z","end":"2026-08-26T00:29:54.389972Z","steps":["trace[163797155] 'agreement among raft nodes before linearized reading' (duration: 238.134073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:54.390116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.912198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:131020"} {"level":"warn","ts":"2026-08-26T00:29:54.390121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.538548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:29:54.390173Z","caller":"traceutil/trace.go:171","msg":"trace[1035616543] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5522; }","duration":"237.969049ms","start":"2026-08-26T00:29:54.152183Z","end":"2026-08-26T00:29:54.390152Z","steps":["trace[1035616543] 'agreement among raft nodes before linearized reading' (duration: 237.742773ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:54.390179Z","caller":"traceutil/trace.go:171","msg":"trace[2068770463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5522; }","duration":"131.660472ms","start":"2026-08-26T00:29:54.258502Z","end":"2026-08-26T00:29:54.390162Z","steps":["trace[2068770463] 'agreement among raft nodes before linearized reading' (duration: 131.547189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:54.390319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.477022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7qh7b\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-26T00:29:54.390379Z","caller":"traceutil/trace.go:171","msg":"trace[1543935531] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7qh7b; range_end:; response_count:1; response_revision:5522; }","duration":"152.549194ms","start":"2026-08-26T00:29:54.237816Z","end":"2026-08-26T00:29:54.390365Z","steps":["trace[1543935531] 'agreement among raft nodes before linearized reading' (duration: 152.349458ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:54.390414Z","caller":"traceutil/trace.go:171","msg":"trace[2019044117] transaction","detail":"{read_only:false; response_revision:5522; number_of_response:1; }","duration":"159.866337ms","start":"2026-08-26T00:29:54.230529Z","end":"2026-08-26T00:29:54.390396Z","steps":["trace[2019044117] 'process raft request' (duration: 159.115506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:54.390411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.391404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7ee073c9-8a93-4c8e-9e71-0226e14ad567\" ","response":"range_response_count:1 size:3953"} {"level":"info","ts":"2026-08-26T00:29:54.390857Z","caller":"traceutil/trace.go:171","msg":"trace[980220650] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7ee073c9-8a93-4c8e-9e71-0226e14ad567; range_end:; response_count:1; response_revision:5522; }","duration":"154.871047ms","start":"2026-08-26T00:29:54.23594Z","end":"2026-08-26T00:29:54.390811Z","steps":["trace[980220650] 'agreement among raft nodes before linearized reading' (duration: 154.22992ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:54.607445Z","caller":"traceutil/trace.go:171","msg":"trace[480226360] transaction","detail":"{read_only:false; response_revision:5524; number_of_response:1; }","duration":"208.699375ms","start":"2026-08-26T00:29:54.398713Z","end":"2026-08-26T00:29:54.607412Z","steps":["trace[480226360] 'process raft request' (duration: 190.813127ms)","trace[480226360] 'compare' (duration: 17.523578ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:54.96303Z","caller":"traceutil/trace.go:171","msg":"trace[805682194] linearizableReadLoop","detail":"{readStateIndex:5735; appliedIndex:5734; }","duration":"136.250069ms","start":"2026-08-26T00:29:54.826731Z","end":"2026-08-26T00:29:54.962981Z","steps":["trace[805682194] 'read index received' (duration: 116.271133ms)","trace[805682194] 'applied index is now lower than readState.Index' (duration: 19.977006ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:54.963237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.462276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:29:54.963279Z","caller":"traceutil/trace.go:171","msg":"trace[1911263123] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5534; }","duration":"136.598779ms","start":"2026-08-26T00:29:54.826668Z","end":"2026-08-26T00:29:54.963266Z","steps":["trace[1911263123] 'agreement among raft nodes before linearized reading' (duration: 136.459675ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:54.963282Z","caller":"traceutil/trace.go:171","msg":"trace[680379987] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"141.798433ms","start":"2026-08-26T00:29:54.821446Z","end":"2026-08-26T00:29:54.963245Z","steps":["trace[680379987] 'process raft request' (duration: 121.614562ms)","trace[680379987] 'compare' (duration: 19.661857ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:55.808068Z","caller":"traceutil/trace.go:171","msg":"trace[1965664306] transaction","detail":"{read_only:false; response_revision:5537; number_of_response:1; }","duration":"100.186317ms","start":"2026-08-26T00:29:55.707829Z","end":"2026-08-26T00:29:55.808015Z","steps":["trace[1965664306] 'process raft request' (duration: 99.881809ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:55.990691Z","caller":"traceutil/trace.go:171","msg":"trace[1528109656] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"132.467444ms","start":"2026-08-26T00:29:55.8582Z","end":"2026-08-26T00:29:55.990667Z","steps":["trace[1528109656] 'process raft request' (duration: 132.398212ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:55.990743Z","caller":"traceutil/trace.go:171","msg":"trace[2100727195] transaction","detail":"{read_only:false; response_revision:5539; number_of_response:1; }","duration":"142.119684ms","start":"2026-08-26T00:29:55.848561Z","end":"2026-08-26T00:29:55.990681Z","steps":["trace[2100727195] 'process raft request' (duration: 111.852882ms)","trace[2100727195] 'compare' (duration: 30.009415ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:55.990856Z","caller":"traceutil/trace.go:171","msg":"trace[1610966425] linearizableReadLoop","detail":"{readStateIndex:5741; appliedIndex:5739; }","duration":"135.213891ms","start":"2026-08-26T00:29:55.855589Z","end":"2026-08-26T00:29:55.990803Z","steps":["trace[1610966425] 'read index received' (duration: 774.491µs)","trace[1610966425] 'applied index is now lower than readState.Index' (duration: 134.43478ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:55.99119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.582211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-26T00:29:55.991259Z","caller":"traceutil/trace.go:171","msg":"trace[1771307960] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5540; }","duration":"135.701144ms","start":"2026-08-26T00:29:55.855543Z","end":"2026-08-26T00:29:55.991244Z","steps":["trace[1771307960] 'agreement among raft nodes before linearized reading' (duration: 135.404686ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:57.112797Z","caller":"traceutil/trace.go:171","msg":"trace[312134729] transaction","detail":"{read_only:false; response_revision:5551; number_of_response:1; }","duration":"240.617143ms","start":"2026-08-26T00:29:56.872154Z","end":"2026-08-26T00:29:57.112771Z","steps":["trace[312134729] 'process raft request' (duration: 204.938871ms)","trace[312134729] 'compare' (duration: 35.24484ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:57.11319Z","caller":"traceutil/trace.go:171","msg":"trace[1990576157] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"241.155758ms","start":"2026-08-26T00:29:56.872004Z","end":"2026-08-26T00:29:57.113159Z","steps":["trace[1990576157] 'process raft request' (duration: 240.574122ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:57.113268Z","caller":"traceutil/trace.go:171","msg":"trace[2069399600] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"238.33925ms","start":"2026-08-26T00:29:56.874918Z","end":"2026-08-26T00:29:57.113257Z","steps":["trace[2069399600] 'process raft request' (duration: 238.186225ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:30:00.629571Z","caller":"traceutil/trace.go:171","msg":"trace[1071243861] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"103.55458ms","start":"2026-08-26T00:30:00.525971Z","end":"2026-08-26T00:30:00.629526Z","steps":["trace[1071243861] 'process raft request' (duration: 48.073936ms)","trace[1071243861] 'compare' (duration: 55.313679ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:30:01.89888Z","caller":"traceutil/trace.go:171","msg":"trace[663716198] linearizableReadLoop","detail":"{readStateIndex:5783; appliedIndex:5782; }","duration":"158.494008ms","start":"2026-08-26T00:30:01.740347Z","end":"2026-08-26T00:30:01.898841Z","steps":["trace[663716198] 'read index received' (duration: 158.316293ms)","trace[663716198] 'applied index is now lower than readState.Index' (duration: 176.245µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:30:01.899006Z","caller":"traceutil/trace.go:171","msg":"trace[1936508497] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"159.789874ms","start":"2026-08-26T00:30:01.739174Z","end":"2026-08-26T00:30:01.898963Z","steps":["trace[1936508497] 'process raft request' (duration: 159.419124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:01.899246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.850337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-08-26T00:30:01.899481Z","caller":"traceutil/trace.go:171","msg":"trace[470577520] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5580; }","duration":"159.133026ms","start":"2026-08-26T00:30:01.740319Z","end":"2026-08-26T00:30:01.899452Z","steps":["trace[470577520] 'agreement among raft nodes before linearized reading' (duration: 158.658373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:02.69792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.940515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2531d490-e23a-495e-9896-c3e0361353ac\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-08-26T00:30:02.698028Z","caller":"traceutil/trace.go:171","msg":"trace[1836236160] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2531d490-e23a-495e-9896-c3e0361353ac; range_end:; response_count:1; response_revision:5585; }","duration":"157.105719ms","start":"2026-08-26T00:30:02.540901Z","end":"2026-08-26T00:30:02.698007Z","steps":["trace[1836236160] 'range keys from in-memory index tree' (duration: 156.762361ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:30:02.718442Z","caller":"traceutil/trace.go:171","msg":"trace[890676077] linearizableReadLoop","detail":"{readStateIndex:5789; appliedIndex:5788; }","duration":"163.307963ms","start":"2026-08-26T00:30:02.555111Z","end":"2026-08-26T00:30:02.718419Z","steps":["trace[890676077] 'read index received' (duration: 163.040466ms)","trace[890676077] 'applied index is now lower than readState.Index' (duration: 265.807µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:30:02.71856Z","caller":"traceutil/trace.go:171","msg":"trace[1825406016] transaction","detail":"{read_only:false; response_revision:5586; number_of_response:1; }","duration":"169.815124ms","start":"2026-08-26T00:30:02.54869Z","end":"2026-08-26T00:30:02.718505Z","steps":["trace[1825406016] 'process raft request' (duration: 169.472384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:02.71863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.500128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:30:02.718691Z","caller":"traceutil/trace.go:171","msg":"trace[797737675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5586; }","duration":"163.597441ms","start":"2026-08-26T00:30:02.555078Z","end":"2026-08-26T00:30:02.718675Z","steps":["trace[797737675] 'agreement among raft nodes before linearized reading' (duration: 163.456697ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:30:02.889365Z","caller":"traceutil/trace.go:171","msg":"trace[534285635] transaction","detail":"{read_only:false; response_revision:5587; number_of_response:1; }","duration":"253.80568ms","start":"2026-08-26T00:30:02.635526Z","end":"2026-08-26T00:30:02.889332Z","steps":["trace[534285635] 'process raft request' (duration: 169.614228ms)","trace[534285635] 'compare' (duration: 83.971146ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:30:02.889524Z","caller":"traceutil/trace.go:171","msg":"trace[945869097] linearizableReadLoop","detail":"{readStateIndex:5791; appliedIndex:5789; }","duration":"170.982236ms","start":"2026-08-26T00:30:02.718523Z","end":"2026-08-26T00:30:02.889505Z","steps":["trace[945869097] 'read index received' (duration: 86.547657ms)","trace[945869097] 'applied index is now lower than readState.Index' (duration: 84.432869ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:30:02.88969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.297349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:30:02.889738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.547035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7qh7b\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-26T00:30:02.889758Z","caller":"traceutil/trace.go:171","msg":"trace[1210460195] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7qh7b; range_end:; response_count:1; response_revision:5588; }","duration":"247.619309ms","start":"2026-08-26T00:30:02.642133Z","end":"2026-08-26T00:30:02.889752Z","steps":["trace[1210460195] 'agreement among raft nodes before linearized reading' (duration: 247.479475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:02.889781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.348771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:30:02.889804Z","caller":"traceutil/trace.go:171","msg":"trace[1751038664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5588; }","duration":"169.422233ms","start":"2026-08-26T00:30:02.720375Z","end":"2026-08-26T00:30:02.889797Z","steps":["trace[1751038664] 'agreement among raft nodes before linearized reading' (duration: 169.385632ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:30:02.889739Z","caller":"traceutil/trace.go:171","msg":"trace[911929804] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:5588; }","duration":"320.390701ms","start":"2026-08-26T00:30:02.569332Z","end":"2026-08-26T00:30:02.889722Z","steps":["trace[911929804] 'agreement among raft nodes before linearized reading' (duration: 320.290958ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:30:02.889823Z","caller":"traceutil/trace.go:171","msg":"trace[944672412] transaction","detail":"{read_only:false; response_revision:5588; number_of_response:1; }","duration":"252.534975ms","start":"2026-08-26T00:30:02.637257Z","end":"2026-08-26T00:30:02.889792Z","steps":["trace[944672412] 'process raft request' (duration: 252.037001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:02.889928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:30:02.56932Z","time spent":"320.550857ms","remote":"127.0.0.1:39522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:30:03.418834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.708977ms","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-08-26T00:30:03.418926Z","caller":"traceutil/trace.go:171","msg":"trace[353275741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5591; }","duration":"189.843451ms","start":"2026-08-26T00:30:03.229058Z","end":"2026-08-26T00:30:03.418902Z","steps":["trace[353275741] 'range keys from in-memory index tree' (duration: 189.541392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:03.419161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.402111ms","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-08-26T00:30:03.419257Z","caller":"traceutil/trace.go:171","msg":"trace[132995546] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5591; }","duration":"220.557035ms","start":"2026-08-26T00:30:03.198677Z","end":"2026-08-26T00:30:03.419234Z","steps":["trace[132995546] 'range keys from in-memory index tree' (duration: 220.150114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:03.419273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.957713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-26T00:30:03.419369Z","caller":"traceutil/trace.go:171","msg":"trace[848249685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5591; }","duration":"149.104527ms","start":"2026-08-26T00:30:03.270243Z","end":"2026-08-26T00:30:03.419348Z","steps":["trace[848249685] 'range keys from in-memory index tree' (duration: 148.83026ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:30:03.583447Z","caller":"traceutil/trace.go:171","msg":"trace[743718421] transaction","detail":"{read_only:false; response_revision:5593; number_of_response:1; }","duration":"159.493455ms","start":"2026-08-26T00:30:03.423898Z","end":"2026-08-26T00:30:03.583392Z","steps":["trace[743718421] 'process raft request' (duration: 140.92542ms)","trace[743718421] 'compare' (duration: 18.209676ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:30:03.583757Z","caller":"traceutil/trace.go:171","msg":"trace[2027007485] transaction","detail":"{read_only:false; response_revision:5594; number_of_response:1; }","duration":"158.416047ms","start":"2026-08-26T00:30:03.425315Z","end":"2026-08-26T00:30:03.583731Z","steps":["trace[2027007485] 'process raft request' (duration: 157.83387ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:30:04.954527Z","caller":"traceutil/trace.go:171","msg":"trace[1362928593] linearizableReadLoop","detail":"{readStateIndex:5803; appliedIndex:5802; }","duration":"131.047785ms","start":"2026-08-26T00:30:04.823436Z","end":"2026-08-26T00:30:04.954483Z","steps":["trace[1362928593] 'read index received' (duration: 105.673819ms)","trace[1362928593] 'applied index is now lower than readState.Index' (duration: 25.370556ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:30:04.954743Z","caller":"traceutil/trace.go:171","msg":"trace[1937074680] transaction","detail":"{read_only:false; response_revision:5600; number_of_response:1; }","duration":"162.070858ms","start":"2026-08-26T00:30:04.792646Z","end":"2026-08-26T00:30:04.954717Z","steps":["trace[1937074680] 'process raft request' (duration: 136.488556ms)","trace[1937074680] 'compare' (duration: 24.683697ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:30:04.954974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.509588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:30:04.955104Z","caller":"traceutil/trace.go:171","msg":"trace[445036371] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:5602; }","duration":"131.676172ms","start":"2026-08-26T00:30:04.823376Z","end":"2026-08-26T00:30:04.955052Z","steps":["trace[445036371] 'agreement among raft nodes before linearized reading' (duration: 131.351613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:05.184846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.217935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:30:05.184992Z","caller":"traceutil/trace.go:171","msg":"trace[1246018891] linearizableReadLoop","detail":"{readStateIndex:5812; appliedIndex:5811; }","duration":"165.280957ms","start":"2026-08-26T00:30:05.019687Z","end":"2026-08-26T00:30:05.184968Z","steps":["trace[1246018891] 'read index received' (duration: 40.708183ms)","trace[1246018891] 'applied index is now lower than readState.Index' (duration: 124.571144ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:30:05.185236Z","caller":"traceutil/trace.go:171","msg":"trace[570741971] transaction","detail":"{read_only:false; response_revision:5609; number_of_response:1; }","duration":"169.633147ms","start":"2026-08-26T00:30:05.015347Z","end":"2026-08-26T00:30:05.18498Z","steps":["trace[570741971] 'process raft request' (duration: 45.152575ms)","trace[570741971] 'compare' (duration: 124.03302ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:30:05.185448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.755591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112532"} {"level":"info","ts":"2026-08-26T00:30:05.185495Z","caller":"traceutil/trace.go:171","msg":"trace[1440909537] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5609; }","duration":"165.847273ms","start":"2026-08-26T00:30:05.019636Z","end":"2026-08-26T00:30:05.185483Z","steps":["trace[1440909537] 'agreement among raft nodes before linearized reading' (duration: 165.394271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:06.242586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.334805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:30:06.242802Z","caller":"traceutil/trace.go:171","msg":"trace[968912493] linearizableReadLoop","detail":"{readStateIndex:5818; appliedIndex:5816; }","duration":"419.036796ms","start":"2026-08-26T00:30:05.823748Z","end":"2026-08-26T00:30:06.242785Z","steps":["trace[968912493] 'read index received' (duration: 109.32125ms)","trace[968912493] 'applied index is now lower than readState.Index' (duration: 309.714186ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:30:06.242985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.233421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.69\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-26T00:30:06.243018Z","caller":"traceutil/trace.go:171","msg":"trace[1559290666] range","detail":"{range_begin:/registry/masterleases/162.253.55.69; range_end:; response_count:1; response_revision:5615; }","duration":"419.316874ms","start":"2026-08-26T00:30:05.823688Z","end":"2026-08-26T00:30:06.243004Z","steps":["trace[1559290666] 'agreement among raft nodes before linearized reading' (duration: 419.155849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:06.243063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:30:05.823661Z","time spent":"419.394086ms","remote":"127.0.0.1:60298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.69\" "} {"level":"info","ts":"2026-08-26T00:30:06.243171Z","caller":"traceutil/trace.go:171","msg":"trace[1008627180] transaction","detail":"{read_only:false; response_revision:5614; number_of_response:1; }","duration":"609.655598ms","start":"2026-08-26T00:30:05.633332Z","end":"2026-08-26T00:30:06.242988Z","steps":["trace[1008627180] 'process raft request' (duration: 299.81263ms)","trace[1008627180] 'compare' (duration: 309.15702ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:30:06.243409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:30:05.633301Z","time spent":"609.952436ms","remote":"127.0.0.1:60548","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-08-26T00:30:06.243705Z","caller":"traceutil/trace.go:171","msg":"trace[2018487963] transaction","detail":"{read_only:false; response_revision:5615; number_of_response:1; }","duration":"609.925645ms","start":"2026-08-26T00:30:05.633746Z","end":"2026-08-26T00:30:06.243671Z","steps":["trace[2018487963] 'process raft request' (duration: 608.960339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:06.243862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:30:05.633735Z","time spent":"610.043039ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:30:06.421764Z","caller":"traceutil/trace.go:171","msg":"trace[906438485] linearizableReadLoop","detail":"{readStateIndex:5821; appliedIndex:5820; }","duration":"106.092841ms","start":"2026-08-26T00:30:06.315642Z","end":"2026-08-26T00:30:06.421734Z","steps":["trace[906438485] 'read index received' (duration: 41.409582ms)","trace[906438485] 'applied index is now lower than readState.Index' (duration: 64.681859ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:30:06.421932Z","caller":"traceutil/trace.go:171","msg":"trace[1342606780] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"115.837463ms","start":"2026-08-26T00:30:06.305946Z","end":"2026-08-26T00:30:06.421784Z","steps":["trace[1342606780] 'process raft request' (duration: 51.097162ms)","trace[1342606780] 'compare' (duration: 64.560176ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:30:06.421968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.297447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-08-26T00:30:06.422066Z","caller":"traceutil/trace.go:171","msg":"trace[1297387441] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5617; }","duration":"106.445561ms","start":"2026-08-26T00:30:06.315596Z","end":"2026-08-26T00:30:06.422041Z","steps":["trace[1297387441] 'agreement among raft nodes before linearized reading' (duration: 106.244365ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:30:52.68748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2968} {"level":"info","ts":"2026-08-26T00:30:52.761992Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2968,"took":"72.716457ms","hash":47787833,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":33730560,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-26T00:30:52.762083Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":47787833,"revision":2968,"compact-revision":-1} {"level":"info","ts":"2026-08-26T00:30:53.490972Z","caller":"traceutil/trace.go:171","msg":"trace[1450729634] linearizableReadLoop","detail":"{readStateIndex:6221; appliedIndex:6220; }","duration":"114.087271ms","start":"2026-08-26T00:30:53.376861Z","end":"2026-08-26T00:30:53.490948Z","steps":["trace[1450729634] 'read index received' (duration: 113.899945ms)","trace[1450729634] 'applied index is now lower than readState.Index' (duration: 185.126µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:30:53.49116Z","caller":"traceutil/trace.go:171","msg":"trace[613863902] transaction","detail":"{read_only:false; response_revision:6005; number_of_response:1; }","duration":"120.500689ms","start":"2026-08-26T00:30:53.370642Z","end":"2026-08-26T00:30:53.491143Z","steps":["trace[613863902] 'process raft request' (duration: 120.112618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:53.493284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.384625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-26T00:30:53.493428Z","caller":"traceutil/trace.go:171","msg":"trace[412314678] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6005; }","duration":"116.53706ms","start":"2026-08-26T00:30:53.376841Z","end":"2026-08-26T00:30:53.493378Z","steps":["trace[412314678] 'agreement among raft nodes before linearized reading' (duration: 114.78832ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:30:54.107261Z","caller":"traceutil/trace.go:171","msg":"trace[2131966414] transaction","detail":"{read_only:false; response_revision:6009; number_of_response:1; }","duration":"111.428497ms","start":"2026-08-26T00:30:53.995798Z","end":"2026-08-26T00:30:54.107227Z","steps":["trace[2131966414] 'process raft request' (duration: 110.654196ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:35:52.699068Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6000} {"level":"info","ts":"2026-08-26T00:35:52.823233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6000,"took":"122.118214ms","hash":1767856033,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":28127232,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-26T00:35:52.823283Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1767856033,"revision":6000,"compact-revision":2968} {"level":"info","ts":"2026-08-26T00:35:55.351347Z","caller":"traceutil/trace.go:171","msg":"trace[958581101] transaction","detail":"{read_only:false; response_revision:8266; number_of_response:1; }","duration":"166.316577ms","start":"2026-08-26T00:35:55.184992Z","end":"2026-08-26T00:35:55.351309Z","steps":["trace[958581101] 'process raft request' (duration: 144.959351ms)","trace[958581101] 'compare' (duration: 21.175131ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:36:07.93365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.389758ms","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-08-26T00:36:07.933719Z","caller":"traceutil/trace.go:171","msg":"trace[703318345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8332; }","duration":"147.507721ms","start":"2026-08-26T00:36:07.786197Z","end":"2026-08-26T00:36:07.933704Z","steps":["trace[703318345] 'range keys from in-memory index tree' (duration: 147.216223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:36:07.933795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.366442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:36:07.933896Z","caller":"traceutil/trace.go:171","msg":"trace[145706606] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8332; }","duration":"263.531507ms","start":"2026-08-26T00:36:07.670342Z","end":"2026-08-26T00:36:07.933874Z","steps":["trace[145706606] 'count revisions from in-memory index tree' (duration: 263.256799ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:34.772452Z","caller":"traceutil/trace.go:171","msg":"trace[562234830] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"263.492226ms","start":"2026-08-26T00:36:34.508937Z","end":"2026-08-26T00:36:34.772429Z","steps":["trace[562234830] 'process raft request' (duration: 263.352272ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:34.837107Z","caller":"traceutil/trace.go:171","msg":"trace[2004856366] linearizableReadLoop","detail":"{readStateIndex:8829; appliedIndex:8827; }","duration":"170.261516ms","start":"2026-08-26T00:36:34.66682Z","end":"2026-08-26T00:36:34.837081Z","steps":["trace[2004856366] 'read index received' (duration: 105.491721ms)","trace[2004856366] 'applied index is now lower than readState.Index' (duration: 64.768765ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:36:34.837264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.422361ms","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:5"} {"level":"info","ts":"2026-08-26T00:36:34.837317Z","caller":"traceutil/trace.go:171","msg":"trace[1415298216] 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:8531; }","duration":"170.541985ms","start":"2026-08-26T00:36:34.666759Z","end":"2026-08-26T00:36:34.837301Z","steps":["trace[1415298216] 'agreement among raft nodes before linearized reading' (duration: 170.420961ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:34.837384Z","caller":"traceutil/trace.go:171","msg":"trace[929097109] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"236.481903ms","start":"2026-08-26T00:36:34.600871Z","end":"2026-08-26T00:36:34.837353Z","steps":["trace[929097109] 'process raft request' (duration: 212.876084ms)","trace[929097109] 'compare' (duration: 23.234459ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:36:41.077344Z","caller":"traceutil/trace.go:171","msg":"trace[1216888363] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"220.341501ms","start":"2026-08-26T00:36:40.856971Z","end":"2026-08-26T00:36:41.077312Z","steps":["trace[1216888363] 'process raft request' (duration: 124.953513ms)","trace[1216888363] 'compare' (duration: 95.235634ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:36:41.215342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.915939ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-26T00:36:41.215447Z","caller":"traceutil/trace.go:171","msg":"trace[933303402] linearizableReadLoop","detail":"{readStateIndex:8865; appliedIndex:8864; }","duration":"128.254426ms","start":"2026-08-26T00:36:41.087176Z","end":"2026-08-26T00:36:41.21543Z","steps":["trace[933303402] 'read index received' (duration: 1.189513ms)","trace[933303402] 'applied index is now lower than readState.Index' (duration: 127.061923ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:36:41.215594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.41183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-08-26T00:36:41.215625Z","caller":"traceutil/trace.go:171","msg":"trace[465142631] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8565; }","duration":"128.472912ms","start":"2026-08-26T00:36:41.087139Z","end":"2026-08-26T00:36:41.215611Z","steps":["trace[465142631] 'agreement among raft nodes before linearized reading' (duration: 128.335258ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:47.877073Z","caller":"traceutil/trace.go:171","msg":"trace[14633937] transaction","detail":"{read_only:false; response_revision:8638; number_of_response:1; }","duration":"164.716232ms","start":"2026-08-26T00:36:47.712323Z","end":"2026-08-26T00:36:47.877039Z","steps":["trace[14633937] 'process raft request' (duration: 158.481048ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:47.894483Z","caller":"traceutil/trace.go:171","msg":"trace[2069613916] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8639; }","duration":"182.110467ms","start":"2026-08-26T00:36:47.712346Z","end":"2026-08-26T00:36:47.894456Z","steps":["trace[2069613916] 'process raft request' (duration: 181.898081ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:47.894562Z","caller":"traceutil/trace.go:171","msg":"trace[85351456] linearizableReadLoop","detail":"{readStateIndex:8942; appliedIndex:8940; }","duration":"162.223262ms","start":"2026-08-26T00:36:47.732322Z","end":"2026-08-26T00:36:47.894545Z","steps":["trace[85351456] 'read index received' (duration: 138.393907ms)","trace[85351456] 'applied index is now lower than readState.Index' (duration: 23.827465ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:36:47.894588Z","caller":"traceutil/trace.go:171","msg":"trace[1265973814] transaction","detail":"{read_only:false; response_revision:8640; number_of_response:1; }","duration":"112.224758ms","start":"2026-08-26T00:36:47.782333Z","end":"2026-08-26T00:36:47.894558Z","steps":["trace[1265973814] 'process raft request' (duration: 112.044913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:36:47.894768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.429728ms","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-08-26T00:36:47.894775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.372794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-26T00:36:47.894844Z","caller":"traceutil/trace.go:171","msg":"trace[1405398163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8640; }","duration":"154.471557ms","start":"2026-08-26T00:36:47.740355Z","end":"2026-08-26T00:36:47.894826Z","steps":["trace[1405398163] 'agreement among raft nodes before linearized reading' (duration: 154.296652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:36:47.894888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.547667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rw6cf\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-08-26T00:36:47.894908Z","caller":"traceutil/trace.go:171","msg":"trace[887782260] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rw6cf; range_end:; response_count:1; response_revision:8640; }","duration":"112.591859ms","start":"2026-08-26T00:36:47.782311Z","end":"2026-08-26T00:36:47.894903Z","steps":["trace[887782260] 'agreement among raft nodes before linearized reading' (duration: 112.537997ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:47.894802Z","caller":"traceutil/trace.go:171","msg":"trace[183956899] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8640; }","duration":"162.50192ms","start":"2026-08-26T00:36:47.73229Z","end":"2026-08-26T00:36:47.894792Z","steps":["trace[183956899] 'agreement among raft nodes before linearized reading' (duration: 162.335205ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:48.006098Z","caller":"traceutil/trace.go:171","msg":"trace[1248249795] linearizableReadLoop","detail":"{readStateIndex:8946; appliedIndex:8944; }","duration":"102.873479ms","start":"2026-08-26T00:36:47.903205Z","end":"2026-08-26T00:36:48.006079Z","steps":["trace[1248249795] 'read index received' (duration: 30.081559ms)","trace[1248249795] 'applied index is now lower than readState.Index' (duration: 72.79091ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:36:48.006217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.997802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rw6cf\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-08-26T00:36:48.006257Z","caller":"traceutil/trace.go:171","msg":"trace[399021610] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rw6cf; range_end:; response_count:1; response_revision:8643; }","duration":"103.069093ms","start":"2026-08-26T00:36:47.903178Z","end":"2026-08-26T00:36:48.006247Z","steps":["trace[399021610] 'agreement among raft nodes before linearized reading' (duration: 102.980021ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:48.006291Z","caller":"traceutil/trace.go:171","msg":"trace[404709852] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"105.308686ms","start":"2026-08-26T00:36:47.900968Z","end":"2026-08-26T00:36:48.006276Z","steps":["trace[404709852] 'process raft request' (duration: 99.537475ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:48.006364Z","caller":"traceutil/trace.go:171","msg":"trace[792607159] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"103.576068ms","start":"2026-08-26T00:36:47.902694Z","end":"2026-08-26T00:36:48.00627Z","steps":["trace[792607159] 'process raft request' (duration: 103.326801ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:37:08.49362Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023af.wal"} {"level":"info","ts":"2026-08-26T00:38:04.96751Z","caller":"traceutil/trace.go:171","msg":"trace[1168187778] transaction","detail":"{read_only:false; response_revision:9328; number_of_response:1; }","duration":"133.483524ms","start":"2026-08-26T00:38:04.833997Z","end":"2026-08-26T00:38:04.967481Z","steps":["trace[1168187778] 'process raft request' (duration: 133.312959ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:05.061677Z","caller":"traceutil/trace.go:171","msg":"trace[319173982] transaction","detail":"{read_only:false; response_revision:9329; number_of_response:1; }","duration":"225.152792ms","start":"2026-08-26T00:38:04.836498Z","end":"2026-08-26T00:38:05.061651Z","steps":["trace[319173982] 'process raft request' (duration: 204.215797ms)","trace[319173982] 'compare' (duration: 20.834682ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:38:05.298381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.887217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:38:05.29849Z","caller":"traceutil/trace.go:171","msg":"trace[267793509] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:9329; }","duration":"133.020471ms","start":"2026-08-26T00:38:05.165447Z","end":"2026-08-26T00:38:05.298467Z","steps":["trace[267793509] 'count revisions from in-memory index tree' (duration: 132.803415ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:05.31265Z","caller":"traceutil/trace.go:171","msg":"trace[664877759] transaction","detail":"{read_only:false; response_revision:9330; number_of_response:1; }","duration":"145.617843ms","start":"2026-08-26T00:38:05.167006Z","end":"2026-08-26T00:38:05.312623Z","steps":["trace[664877759] 'process raft request' (duration: 145.454279ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:05.468419Z","caller":"traceutil/trace.go:171","msg":"trace[1150070491] transaction","detail":"{read_only:false; response_revision:9331; number_of_response:1; }","duration":"121.822128ms","start":"2026-08-26T00:38:05.346571Z","end":"2026-08-26T00:38:05.468393Z","steps":["trace[1150070491] 'process raft request' (duration: 74.757595ms)","trace[1150070491] 'compare' (duration: 46.896638ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:38:42.13356Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"905ccde8a677f143","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-26T00:38:42.138736Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-26T00:38:42.138843Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-26T00:40:52.704268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8255} {"level":"info","ts":"2026-08-26T00:40:52.784127Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8255,"took":"77.771176ms","hash":1591476140,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":26374144,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-26T00:40:52.784189Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1591476140,"revision":8255,"compact-revision":6000} {"level":"info","ts":"2026-08-26T00:41:43.813509Z","caller":"traceutil/trace.go:171","msg":"trace[2046877675] transaction","detail":"{read_only:false; response_revision:11251; number_of_response:1; }","duration":"106.180022ms","start":"2026-08-26T00:41:43.707295Z","end":"2026-08-26T00:41:43.813476Z","steps":["trace[2046877675] 'process raft request' (duration: 105.999597ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:45:52.711311Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10772} {"level":"info","ts":"2026-08-26T00:45:52.795227Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10772,"took":"81.409471ms","hash":2216260695,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":27148288,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-26T00:45:52.795285Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2216260695,"revision":10772,"compact-revision":8255} {"level":"info","ts":"2026-08-26T00:47:55.710483Z","caller":"traceutil/trace.go:171","msg":"trace[1896277867] transaction","detail":"{read_only:false; response_revision:13756; number_of_response:1; }","duration":"146.145206ms","start":"2026-08-26T00:47:55.564307Z","end":"2026-08-26T00:47:55.710453Z","steps":["trace[1896277867] 'process raft request' (duration: 145.997452ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:48:10.333551Z","caller":"traceutil/trace.go:171","msg":"trace[215977897] transaction","detail":"{read_only:false; response_revision:13849; number_of_response:1; }","duration":"117.773875ms","start":"2026-08-26T00:48:10.215737Z","end":"2026-08-26T00:48:10.333511Z","steps":["trace[215977897] 'process raft request' (duration: 117.553569ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:48:33.764247Z","caller":"traceutil/trace.go:171","msg":"trace[2089592477] transaction","detail":"{read_only:false; response_revision:14163; number_of_response:1; }","duration":"181.016358ms","start":"2026-08-26T00:48:33.583208Z","end":"2026-08-26T00:48:33.764225Z","steps":["trace[2089592477] 'process raft request' (duration: 180.894385ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:48:33.7643Z","caller":"traceutil/trace.go:171","msg":"trace[316863659] linearizableReadLoop","detail":"{readStateIndex:14650; appliedIndex:14650; }","duration":"136.721643ms","start":"2026-08-26T00:48:33.627556Z","end":"2026-08-26T00:48:33.764277Z","steps":["trace[316863659] 'read index received' (duration: 136.713793ms)","trace[316863659] 'applied index is now lower than readState.Index' (duration: 6.17µs)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:48:33.764403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.839946ms","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-08-26T00:48:33.76443Z","caller":"traceutil/trace.go:171","msg":"trace[1447278070] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14163; }","duration":"136.913748ms","start":"2026-08-26T00:48:33.627506Z","end":"2026-08-26T00:48:33.76442Z","steps":["trace[1447278070] 'agreement among raft nodes before linearized reading' (duration: 136.849877ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:49:38.478273Z","caller":"traceutil/trace.go:171","msg":"trace[1780469358] linearizableReadLoop","detail":"{readStateIndex:15364; appliedIndex:15363; }","duration":"359.935019ms","start":"2026-08-26T00:49:38.118298Z","end":"2026-08-26T00:49:38.478233Z","steps":["trace[1780469358] 'read index received' (duration: 359.764044ms)","trace[1780469358] 'applied index is now lower than readState.Index' (duration: 169.645µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:49:38.478373Z","caller":"traceutil/trace.go:171","msg":"trace[1706950031] transaction","detail":"{read_only:false; response_revision:14859; number_of_response:1; }","duration":"444.452255ms","start":"2026-08-26T00:49:38.033883Z","end":"2026-08-26T00:49:38.478335Z","steps":["trace[1706950031] 'process raft request' (duration: 444.089885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:49:38.478584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.258678ms","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-08-26T00:49:38.478708Z","caller":"traceutil/trace.go:171","msg":"trace[136399412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14859; }","duration":"360.463293ms","start":"2026-08-26T00:49:38.118223Z","end":"2026-08-26T00:49:38.478686Z","steps":["trace[136399412] 'agreement among raft nodes before linearized reading' (duration: 360.158395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:49:38.478768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:49:38.118193Z","time spent":"360.563476ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-26T00:49:38.478605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:49:38.033854Z","time spent":"444.637051ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:49:38.49196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.897024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-08-26T00:49:38.492012Z","caller":"traceutil/trace.go:171","msg":"trace[2097183840] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14860; }","duration":"314.165092ms","start":"2026-08-26T00:49:38.177835Z","end":"2026-08-26T00:49:38.492Z","steps":["trace[2097183840] 'agreement among raft nodes before linearized reading' (duration: 313.997647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:49:38.492041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:49:38.177813Z","time spent":"314.223564ms","remote":"127.0.0.1:60374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":379,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-08-26T00:49:38.492322Z","caller":"traceutil/trace.go:171","msg":"trace[1248557865] transaction","detail":"{read_only:false; response_revision:14860; number_of_response:1; }","duration":"202.24686ms","start":"2026-08-26T00:49:38.290067Z","end":"2026-08-26T00:49:38.492314Z","steps":["trace[1248557865] 'process raft request' (duration: 201.653144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:49:38.492797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.636043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-26T00:49:38.492831Z","caller":"traceutil/trace.go:171","msg":"trace[1176986131] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14860; }","duration":"164.719324ms","start":"2026-08-26T00:49:38.328102Z","end":"2026-08-26T00:49:38.492821Z","steps":["trace[1176986131] 'agreement among raft nodes before linearized reading' (duration: 164.638522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:49:38.492858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.123506ms","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-08-26T00:49:38.492943Z","caller":"traceutil/trace.go:171","msg":"trace[922241826] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14860; }","duration":"160.229288ms","start":"2026-08-26T00:49:38.332695Z","end":"2026-08-26T00:49:38.492924Z","steps":["trace[922241826] 'agreement among raft nodes before linearized reading' (duration: 160.001232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:49:38.49296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.982552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:49:38.492984Z","caller":"traceutil/trace.go:171","msg":"trace[143840853] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14860; }","duration":"165.067174ms","start":"2026-08-26T00:49:38.32791Z","end":"2026-08-26T00:49:38.492977Z","steps":["trace[143840853] 'agreement among raft nodes before linearized reading' (duration: 165.030183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:49:38.493112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.220424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-26T00:49:38.493141Z","caller":"traceutil/trace.go:171","msg":"trace[1342554019] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14860; }","duration":"200.294876ms","start":"2026-08-26T00:49:38.292837Z","end":"2026-08-26T00:49:38.493131Z","steps":["trace[1342554019] 'agreement among raft nodes before linearized reading' (duration: 200.224404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:49:38.493262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.967923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:49:38.494445Z","caller":"traceutil/trace.go:171","msg":"trace[487248485] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:14860; }","duration":"212.200718ms","start":"2026-08-26T00:49:38.282218Z","end":"2026-08-26T00:49:38.494419Z","steps":["trace[487248485] 'agreement among raft nodes before linearized reading' (duration: 211.011815ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:50:52.716592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12845} {"level":"info","ts":"2026-08-26T00:50:52.793018Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12845,"took":"73.919013ms","hash":1151386228,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":29962240,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-26T00:50:52.793092Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1151386228,"revision":12845,"compact-revision":10772} {"level":"info","ts":"2026-08-26T00:52:57.989508Z","caller":"traceutil/trace.go:171","msg":"trace[288470208] transaction","detail":"{read_only:false; response_revision:16419; number_of_response:1; }","duration":"100.816914ms","start":"2026-08-26T00:52:57.888653Z","end":"2026-08-26T00:52:57.98947Z","steps":["trace[288470208] 'process raft request' (duration: 41.008614ms)","trace[288470208] 'compare' (duration: 59.641405ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:55:45.991822Z","caller":"traceutil/trace.go:171","msg":"trace[1433650236] transaction","detail":"{read_only:false; response_revision:17545; number_of_response:1; }","duration":"116.258388ms","start":"2026-08-26T00:55:45.875545Z","end":"2026-08-26T00:55:45.991803Z","steps":["trace[1433650236] 'process raft request' (duration: 116.141054ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:55:46.269942Z","caller":"traceutil/trace.go:171","msg":"trace[542964488] transaction","detail":"{read_only:false; response_revision:17546; number_of_response:1; }","duration":"379.159056ms","start":"2026-08-26T00:55:45.890695Z","end":"2026-08-26T00:55:46.269855Z","steps":["trace[542964488] 'process raft request' (duration: 378.132948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:55:46.270086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.729148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.69\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-08-26T00:55:46.270134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:55:45.89067Z","time spent":"379.373851ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:55:46.270159Z","caller":"traceutil/trace.go:171","msg":"trace[361698637] range","detail":"{range_begin:/registry/masterleases/162.253.55.69; range_end:; response_count:1; response_revision:17546; }","duration":"355.83658ms","start":"2026-08-26T00:55:45.914304Z","end":"2026-08-26T00:55:46.27014Z","steps":["trace[361698637] 'agreement among raft nodes before linearized reading' (duration: 355.634784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:55:46.270205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:55:45.91429Z","time spent":"355.902643ms","remote":"127.0.0.1:60298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/162.253.55.69\" "} {"level":"info","ts":"2026-08-26T00:55:46.269648Z","caller":"traceutil/trace.go:171","msg":"trace[19026581] linearizableReadLoop","detail":"{readStateIndex:18139; appliedIndex:18137; }","duration":"355.260034ms","start":"2026-08-26T00:55:45.91434Z","end":"2026-08-26T00:55:46.2696Z","steps":["trace[19026581] 'read index received' (duration: 77.345763ms)","trace[19026581] 'applied index is now lower than readState.Index' (duration: 277.911891ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:55:46.507843Z","caller":"traceutil/trace.go:171","msg":"trace[1821726148] transaction","detail":"{read_only:false; response_revision:17548; number_of_response:1; }","duration":"101.720118ms","start":"2026-08-26T00:55:46.406102Z","end":"2026-08-26T00:55:46.507822Z","steps":["trace[1821726148] 'process raft request' (duration: 101.656047ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:55:46.508155Z","caller":"traceutil/trace.go:171","msg":"trace[716473451] transaction","detail":"{read_only:false; response_revision:17547; number_of_response:1; }","duration":"227.078982ms","start":"2026-08-26T00:55:46.281056Z","end":"2026-08-26T00:55:46.508135Z","steps":["trace[716473451] 'process raft request' (duration: 180.765381ms)","trace[716473451] 'compare' (duration: 45.786497ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:55:46.508206Z","caller":"traceutil/trace.go:171","msg":"trace[1073462851] linearizableReadLoop","detail":"{readStateIndex:18141; appliedIndex:18140; }","duration":"226.613199ms","start":"2026-08-26T00:55:46.281554Z","end":"2026-08-26T00:55:46.508167Z","steps":["trace[1073462851] 'read index received' (duration: 180.255656ms)","trace[1073462851] 'applied index is now lower than readState.Index' (duration: 46.353763ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:55:46.508763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.655518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-26T00:55:46.508902Z","caller":"traceutil/trace.go:171","msg":"trace[54688478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17548; }","duration":"185.788071ms","start":"2026-08-26T00:55:46.32303Z","end":"2026-08-26T00:55:46.508818Z","steps":["trace[54688478] 'agreement among raft nodes before linearized reading' (duration: 185.587656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:55:46.509382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.817513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945801"} {"level":"info","ts":"2026-08-26T00:55:46.509438Z","caller":"traceutil/trace.go:171","msg":"trace[1655633454] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17548; }","duration":"227.901686ms","start":"2026-08-26T00:55:46.281524Z","end":"2026-08-26T00:55:46.509426Z","steps":["trace[1655633454] 'agreement among raft nodes before linearized reading' (duration: 226.902667ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:55:52.723948Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15302} {"level":"info","ts":"2026-08-26T00:55:52.81512Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15302,"took":"88.694182ms","hash":3695885514,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":31162368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-26T00:55:52.81519Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3695885514,"revision":15302,"compact-revision":12845} {"level":"info","ts":"2026-08-26T00:59:28.114725Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"905ccde8a677f143","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-26T00:59:28.120483Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-26T00:59:28.120592Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-26T01:00:52.734339Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17592} {"level":"info","ts":"2026-08-26T01:00:52.834656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17592,"took":"95.9278ms","hash":2176166552,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":34078720,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-26T01:00:52.834745Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2176166552,"revision":17592,"compact-revision":15302} {"level":"info","ts":"2026-08-26T01:01:34.957829Z","caller":"traceutil/trace.go:171","msg":"trace[1504147139] transaction","detail":"{read_only:false; response_revision:20293; number_of_response:1; }","duration":"170.663148ms","start":"2026-08-26T01:01:34.787142Z","end":"2026-08-26T01:01:34.957805Z","steps":["trace[1504147139] 'process raft request' (duration: 170.475223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:01:35.297512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.182704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-26T01:01:35.297644Z","caller":"traceutil/trace.go:171","msg":"trace[410319079] linearizableReadLoop","detail":"{readStateIndex:20979; appliedIndex:20977; }","duration":"458.691776ms","start":"2026-08-26T01:01:34.838934Z","end":"2026-08-26T01:01:35.297625Z","steps":["trace[410319079] 'read index received' (duration: 118.665116ms)","trace[410319079] 'applied index is now lower than readState.Index' (duration: 340.02487ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T01:01:35.297746Z","caller":"traceutil/trace.go:171","msg":"trace[1123013727] transaction","detail":"{read_only:false; response_revision:20294; number_of_response:1; }","duration":"508.102586ms","start":"2026-08-26T01:01:34.789631Z","end":"2026-08-26T01:01:35.297733Z","steps":["trace[1123013727] 'process raft request' (duration: 340.995507ms)","trace[1123013727] 'compare' (duration: 165.680269ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T01:01:35.297802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T01:01:34.789584Z","time spent":"508.187318ms","remote":"127.0.0.1:60548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T01:01:35.298162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.57575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-26T01:01:35.298215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.245931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-26T01:01:35.298272Z","caller":"traceutil/trace.go:171","msg":"trace[854248035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20294; }","duration":"420.727794ms","start":"2026-08-26T01:01:34.877519Z","end":"2026-08-26T01:01:35.298247Z","steps":["trace[854248035] 'agreement among raft nodes before linearized reading' (duration: 420.398845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:01:35.298339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T01:01:34.877509Z","time spent":"420.819987ms","remote":"127.0.0.1:60548","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":"info","ts":"2026-08-26T01:01:35.298335Z","caller":"traceutil/trace.go:171","msg":"trace[936114123] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20294; }","duration":"459.444617ms","start":"2026-08-26T01:01:34.838865Z","end":"2026-08-26T01:01:35.29831Z","steps":["trace[936114123] 'agreement among raft nodes before linearized reading' (duration: 459.050336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:01:35.29832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.335606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7913"} {"level":"warn","ts":"2026-08-26T01:01:35.298429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T01:01:34.838839Z","time spent":"459.552519ms","remote":"127.0.0.1:60460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-26T01:01:35.298514Z","caller":"traceutil/trace.go:171","msg":"trace[70146712] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:20294; }","duration":"331.526632ms","start":"2026-08-26T01:01:34.966893Z","end":"2026-08-26T01:01:35.298419Z","steps":["trace[70146712] 'agreement among raft nodes before linearized reading' (duration: 331.084829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:01:35.298598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T01:01:34.966873Z","time spent":"331.710327ms","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7936,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-08-26T01:01:35.569631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.991685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-26T01:01:35.569803Z","caller":"traceutil/trace.go:171","msg":"trace[132636098] linearizableReadLoop","detail":"{readStateIndex:20980; appliedIndex:20979; }","duration":"264.372206ms","start":"2026-08-26T01:01:35.305414Z","end":"2026-08-26T01:01:35.569786Z","steps":["trace[132636098] 'read index received' (duration: 126.158684ms)","trace[132636098] 'applied index is now lower than readState.Index' (duration: 138.212331ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T01:01:35.569912Z","caller":"traceutil/trace.go:171","msg":"trace[356317586] transaction","detail":"{read_only:false; response_revision:20295; number_of_response:1; }","duration":"266.269689ms","start":"2026-08-26T01:01:35.303631Z","end":"2026-08-26T01:01:35.569901Z","steps":["trace[356317586] 'process raft request' (duration: 127.919134ms)","trace[356317586] 'compare' (duration: 137.638235ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T01:01:35.570112Z","caller":"traceutil/trace.go:171","msg":"trace[936395983] transaction","detail":"{read_only:false; response_revision:20296; number_of_response:1; }","duration":"263.354767ms","start":"2026-08-26T01:01:35.306721Z","end":"2026-08-26T01:01:35.570075Z","steps":["trace[936395983] 'process raft request' (duration: 263.025218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:01:35.570271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.86813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18260"} {"level":"info","ts":"2026-08-26T01:01:35.57032Z","caller":"traceutil/trace.go:171","msg":"trace[188658969] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20296; }","duration":"264.970222ms","start":"2026-08-26T01:01:35.305338Z","end":"2026-08-26T01:01:35.570308Z","steps":["trace[188658969] 'agreement among raft nodes before linearized reading' (duration: 264.769336ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:01:35.772285Z","caller":"traceutil/trace.go:171","msg":"trace[1056405371] transaction","detail":"{read_only:false; response_revision:20297; number_of_response:1; }","duration":"188.749074ms","start":"2026-08-26T01:01:35.583511Z","end":"2026-08-26T01:01:35.77226Z","steps":["trace[1056405371] 'process raft request' (duration: 188.212888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:01:36.009142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.267889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-26T01:01:36.009671Z","caller":"traceutil/trace.go:171","msg":"trace[935769373] transaction","detail":"{read_only:false; response_revision:20298; number_of_response:1; }","duration":"208.867785ms","start":"2026-08-26T01:01:35.800441Z","end":"2026-08-26T01:01:36.009309Z","steps":["trace[935769373] 'process raft request' (duration: 58.313909ms)","trace[935769373] 'compare' (duration: 150.105594ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T01:01:36.463051Z","caller":"traceutil/trace.go:171","msg":"trace[26086382] transaction","detail":"{read_only:false; response_revision:20300; number_of_response:1; }","duration":"149.041424ms","start":"2026-08-26T01:01:36.31397Z","end":"2026-08-26T01:01:36.463012Z","steps":["trace[26086382] 'process raft request' (duration: 148.720015ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:01:36.637517Z","caller":"traceutil/trace.go:171","msg":"trace[978613455] transaction","detail":"{read_only:false; response_revision:20302; number_of_response:1; }","duration":"168.183418ms","start":"2026-08-26T01:01:36.4693Z","end":"2026-08-26T01:01:36.637483Z","steps":["trace[978613455] 'process raft request' (duration: 167.823108ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:01:36.63755Z","caller":"traceutil/trace.go:171","msg":"trace[1136301784] transaction","detail":"{read_only:false; response_revision:20303; number_of_response:1; }","duration":"166.717377ms","start":"2026-08-26T01:01:36.470797Z","end":"2026-08-26T01:01:36.637514Z","steps":["trace[1136301784] 'process raft request' (duration: 166.579233ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:01:36.637502Z","caller":"traceutil/trace.go:171","msg":"trace[236971593] linearizableReadLoop","detail":"{readStateIndex:20989; appliedIndex:20986; }","duration":"164.153686ms","start":"2026-08-26T01:01:36.473302Z","end":"2026-08-26T01:01:36.637456Z","steps":["trace[236971593] 'read index received' (duration: 20.183884ms)","trace[236971593] 'applied index is now lower than readState.Index' (duration: 143.968292ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T01:01:36.637763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.453804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18260"} {"level":"info","ts":"2026-08-26T01:01:36.637825Z","caller":"traceutil/trace.go:171","msg":"trace[2078456938] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:20303; }","duration":"164.545946ms","start":"2026-08-26T01:01:36.473264Z","end":"2026-08-26T01:01:36.63781Z","steps":["trace[2078456938] 'agreement among raft nodes before linearized reading' (duration: 164.31658ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:05:30.61886Z","caller":"traceutil/trace.go:171","msg":"trace[676863270] linearizableReadLoop","detail":"{readStateIndex:23533; appliedIndex:23532; }","duration":"121.091103ms","start":"2026-08-26T01:05:30.497742Z","end":"2026-08-26T01:05:30.618833Z","steps":["trace[676863270] 'read index received' (duration: 120.813346ms)","trace[676863270] 'applied index is now lower than readState.Index' (duration: 276.307µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T01:05:30.618973Z","caller":"traceutil/trace.go:171","msg":"trace[1593896187] transaction","detail":"{read_only:false; response_revision:22774; number_of_response:1; }","duration":"131.019731ms","start":"2026-08-26T01:05:30.487908Z","end":"2026-08-26T01:05:30.618927Z","steps":["trace[1593896187] 'process raft request' (duration: 130.757134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:05:30.619125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.35532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-26T01:05:30.619207Z","caller":"traceutil/trace.go:171","msg":"trace[915890095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22774; }","duration":"121.485004ms","start":"2026-08-26T01:05:30.497699Z","end":"2026-08-26T01:05:30.619184Z","steps":["trace[915890095] 'agreement among raft nodes before linearized reading' (duration: 121.274808ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:05:52.741221Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20082} {"level":"info","ts":"2026-08-26T01:05:52.858513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20082,"took":"113.259555ms","hash":3153768402,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":42823680,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-26T01:05:52.858636Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3153768402,"revision":20082,"compact-revision":17592} {"level":"info","ts":"2026-08-26T01:10:52.747819Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23027} {"level":"info","ts":"2026-08-26T01:10:52.885207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23027,"took":"132.974914ms","hash":3330474732,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":32669696,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-26T01:10:52.885265Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3330474732,"revision":23027,"compact-revision":20082}