{"level":"warn","ts":"2026-05-16T01:23:26.048062Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-16T01:23:26.048206Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-16T01:23:26.048332Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-16T01:23:26.048358Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-16T01:23:26.04839Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-16T01:23:26.049509Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-05-16T01:23:26.04965Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-16T01:23:26.053807Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.755769ms"} {"level":"info","ts":"2026-05-16T01:23:26.060736Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-05-16T01:23:26.060855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-05-16T01:23:26.060884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-05-16T01:23:26.060902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-16T01:23:26.060919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-05-16T01:23:26.060966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-05-16T01:23:26.065686Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-16T01:23:26.06717Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-16T01:23:26.068303Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-16T01:23:26.070518Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-16T01:23:26.070716Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-16T01:23:26.070788Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-16T01:23:26.071025Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-16T01:23:26.071082Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-16T01:23:26.074048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-05-16T01:23:26.074292Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-16T01:23:26.076691Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-16T01:23:26.076789Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-16T01:23:26.076837Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-16T01:23:26.077227Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-16T01:23:26.077327Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-16T01:23:26.562041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-05-16T01:23:26.562147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-16T01:23:26.56219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-05-16T01:23:26.562213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-05-16T01:23:26.562226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-16T01:23:26.562242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-05-16T01:23:26.562263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-16T01:23:26.563355Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-16T01:23:26.563356Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-16T01:23:26.563367Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-16T01:23:26.5635Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-16T01:23:26.56373Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-16T01:23:26.563773Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-16T01:23:26.564438Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-05-16T01:23:26.564566Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-16T01:23:26.564614Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-16T01:23:26.567704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-16T01:23:26.569593Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-05-16T01:23:47.979594Z","caller":"traceutil/trace.go:171","msg":"trace[1317786669] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"145.787965ms","start":"2026-05-16T01:23:47.833783Z","end":"2026-05-16T01:23:47.979571Z","steps":["trace[1317786669] 'process raft request' (duration: 145.655562ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:23:53.446568Z","caller":"traceutil/trace.go:171","msg":"trace[1256155307] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"115.735353ms","start":"2026-05-16T01:23:53.330812Z","end":"2026-05-16T01:23:53.446547Z","steps":["trace[1256155307] 'process raft request' (duration: 115.608521ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:24:04.564399Z","caller":"traceutil/trace.go:171","msg":"trace[1582139962] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"179.508623ms","start":"2026-05-16T01:24:04.384858Z","end":"2026-05-16T01:24:04.564367Z","steps":["trace[1582139962] 'read index received' (duration: 174.997122ms)","trace[1582139962] 'applied index is now lower than readState.Index' (duration: 4.510241ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T01:24:04.564639Z","caller":"traceutil/trace.go:171","msg":"trace[2097242080] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"398.996646ms","start":"2026-05-16T01:24:04.165454Z","end":"2026-05-16T01:24:04.564451Z","steps":["trace[2097242080] 'process raft request' (duration: 394.407474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:24:04.564702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.809931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-16T01:24:04.565095Z","caller":"traceutil/trace.go:171","msg":"trace[1315570812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"180.259879ms","start":"2026-05-16T01:24:04.384805Z","end":"2026-05-16T01:24:04.565064Z","steps":["trace[1315570812] 'agreement among raft nodes before linearized reading' (duration: 179.671687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:24:04.565606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T01:24:04.165433Z","time spent":"399.429516ms","remote":"127.0.0.1:32906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-16T01:24:20.392738Z","caller":"traceutil/trace.go:171","msg":"trace[1262497902] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"212.614054ms","start":"2026-05-16T01:24:20.180104Z","end":"2026-05-16T01:24:20.392718Z","steps":["trace[1262497902] 'process raft request' (duration: 212.509282ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:25:38.462111Z","caller":"traceutil/trace.go:171","msg":"trace[420261491] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"144.137208ms","start":"2026-05-16T01:25:38.317948Z","end":"2026-05-16T01:25:38.462085Z","steps":["trace[420261491] 'read index received' (duration: 143.989785ms)","trace[420261491] 'applied index is now lower than readState.Index' (duration: 145.893µs)"],"step_count":2} {"level":"info","ts":"2026-05-16T01:25:38.462215Z","caller":"traceutil/trace.go:171","msg":"trace[650475823] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"266.975992ms","start":"2026-05-16T01:25:38.195202Z","end":"2026-05-16T01:25:38.462178Z","steps":["trace[650475823] 'process raft request' (duration: 266.715437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:25:38.462326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.366292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-16T01:25:38.462371Z","caller":"traceutil/trace.go:171","msg":"trace[1683537692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1863; }","duration":"144.511985ms","start":"2026-05-16T01:25:38.317848Z","end":"2026-05-16T01:25:38.46236Z","steps":["trace[1683537692] 'agreement among raft nodes before linearized reading' (duration: 144.340122ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:25:55.252953Z","caller":"traceutil/trace.go:171","msg":"trace[1160096867] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2078; }","duration":"107.668497ms","start":"2026-05-16T01:25:55.14526Z","end":"2026-05-16T01:25:55.252928Z","steps":["trace[1160096867] 'read index received' (duration: 107.013504ms)","trace[1160096867] 'applied index is now lower than readState.Index' (duration: 653.593µs)"],"step_count":2} {"level":"warn","ts":"2026-05-16T01:25:55.253089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.814691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-16T01:25:55.253116Z","caller":"traceutil/trace.go:171","msg":"trace[800244931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2004; }","duration":"107.880131ms","start":"2026-05-16T01:25:55.145228Z","end":"2026-05-16T01:25:55.253108Z","steps":["trace[800244931] 'agreement among raft nodes before linearized reading' (duration: 107.81741ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:25:55.252997Z","caller":"traceutil/trace.go:171","msg":"trace[446524466] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"115.408384ms","start":"2026-05-16T01:25:55.137566Z","end":"2026-05-16T01:25:55.252974Z","steps":["trace[446524466] 'process raft request' (duration: 114.72452ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:25:57.089595Z","caller":"traceutil/trace.go:171","msg":"trace[809973275] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"102.738426ms","start":"2026-05-16T01:25:56.986826Z","end":"2026-05-16T01:25:57.089564Z","steps":["trace[809973275] 'process raft request' (duration: 102.620094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:26:33.746369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.046362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-16T01:26:33.746524Z","caller":"traceutil/trace.go:171","msg":"trace[1627575431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2214; }","duration":"165.280937ms","start":"2026-05-16T01:26:33.581218Z","end":"2026-05-16T01:26:33.746499Z","steps":["trace[1627575431] 'range keys from in-memory index tree' (duration: 164.781276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:26:33.746402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.422394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-16T01:26:33.746809Z","caller":"traceutil/trace.go:171","msg":"trace[823464988] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2214; }","duration":"133.807631ms","start":"2026-05-16T01:26:33.612904Z","end":"2026-05-16T01:26:33.746712Z","steps":["trace[823464988] 'range keys from in-memory index tree' (duration: 133.21722ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:27:17.842607Z","caller":"traceutil/trace.go:171","msg":"trace[980092658] transaction","detail":"{read_only:false; response_revision:2627; number_of_response:1; }","duration":"183.209986ms","start":"2026-05-16T01:27:17.659359Z","end":"2026-05-16T01:27:17.842569Z","steps":["trace[980092658] 'process raft request' (duration: 182.987482ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:31:53.205461Z","caller":"traceutil/trace.go:171","msg":"trace[1213131798] linearizableReadLoop","detail":"{readStateIndex:5215; appliedIndex:5214; }","duration":"105.018697ms","start":"2026-05-16T01:31:53.100383Z","end":"2026-05-16T01:31:53.205401Z","steps":["trace[1213131798] 'read index received' (duration: 104.789143ms)","trace[1213131798] 'applied index is now lower than readState.Index' (duration: 227.874µs)"],"step_count":2} {"level":"info","ts":"2026-05-16T01:31:53.205598Z","caller":"traceutil/trace.go:171","msg":"trace[77688101] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"105.747022ms","start":"2026-05-16T01:31:53.099807Z","end":"2026-05-16T01:31:53.205554Z","steps":["trace[77688101] 'process raft request' (duration: 105.385165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:31:53.205666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.240593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-16T01:31:53.205773Z","caller":"traceutil/trace.go:171","msg":"trace[142765371] range","detail":"{range_begin:/registry/clusterroles/vector; range_end:; response_count:0; response_revision:5023; }","duration":"105.396895ms","start":"2026-05-16T01:31:53.100355Z","end":"2026-05-16T01:31:53.205752Z","steps":["trace[142765371] 'agreement among raft nodes before linearized reading' (duration: 105.200891ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:31:53.207139Z","caller":"traceutil/trace.go:171","msg":"trace[1857463804] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"102.622208ms","start":"2026-05-16T01:31:53.1045Z","end":"2026-05-16T01:31:53.207122Z","steps":["trace[1857463804] 'process raft request' (duration: 102.345523ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:31:53.534927Z","caller":"traceutil/trace.go:171","msg":"trace[1970552180] linearizableReadLoop","detail":"{readStateIndex:5227; appliedIndex:5225; }","duration":"173.757075ms","start":"2026-05-16T01:31:53.361137Z","end":"2026-05-16T01:31:53.534894Z","steps":["trace[1970552180] 'read index received' (duration: 141.604151ms)","trace[1970552180] 'applied index is now lower than readState.Index' (duration: 32.151624ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T01:31:53.534965Z","caller":"traceutil/trace.go:171","msg":"trace[243517091] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"193.066143ms","start":"2026-05-16T01:31:53.341828Z","end":"2026-05-16T01:31:53.534894Z","steps":["trace[243517091] 'process raft request' (duration: 160.924529ms)","trace[243517091] 'compare' (duration: 31.876139ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T01:31:53.535007Z","caller":"traceutil/trace.go:171","msg":"trace[141129050] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"192.302467ms","start":"2026-05-16T01:31:53.342661Z","end":"2026-05-16T01:31:53.534964Z","steps":["trace[141129050] 'process raft request' (duration: 192.171364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:31:53.535193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.032571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-16T01:31:53.535223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.03056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-16T01:31:53.535235Z","caller":"traceutil/trace.go:171","msg":"trace[161791467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5034; }","duration":"174.145683ms","start":"2026-05-16T01:31:53.361079Z","end":"2026-05-16T01:31:53.535225Z","steps":["trace[161791467] 'agreement among raft nodes before linearized reading' (duration: 173.916088ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:31:53.535255Z","caller":"traceutil/trace.go:171","msg":"trace[282979390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5034; }","duration":"152.125451ms","start":"2026-05-16T01:31:53.383121Z","end":"2026-05-16T01:31:53.535247Z","steps":["trace[282979390] 'agreement among raft nodes before linearized reading' (duration: 151.947838ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:32:04.272468Z","caller":"traceutil/trace.go:171","msg":"trace[59250202] linearizableReadLoop","detail":"{readStateIndex:5447; appliedIndex:5446; }","duration":"162.607572ms","start":"2026-05-16T01:32:04.109833Z","end":"2026-05-16T01:32:04.272441Z","steps":["trace[59250202] 'read index received' (duration: 162.211915ms)","trace[59250202] 'applied index is now lower than readState.Index' (duration: 394.707µs)"],"step_count":2} {"level":"info","ts":"2026-05-16T01:32:04.272538Z","caller":"traceutil/trace.go:171","msg":"trace[1817350594] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"205.864499ms","start":"2026-05-16T01:32:04.066623Z","end":"2026-05-16T01:32:04.272488Z","steps":["trace[1817350594] 'process raft request' (duration: 205.543443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:32:04.272708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.711182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-16T01:32:04.272757Z","caller":"traceutil/trace.go:171","msg":"trace[323505532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5247; }","duration":"126.799503ms","start":"2026-05-16T01:32:04.145942Z","end":"2026-05-16T01:32:04.272742Z","steps":["trace[323505532] 'agreement among raft nodes before linearized reading' (duration: 126.656161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:32:04.272823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.976659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-16T01:32:04.27292Z","caller":"traceutil/trace.go:171","msg":"trace[745623955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5247; }","duration":"163.109862ms","start":"2026-05-16T01:32:04.109789Z","end":"2026-05-16T01:32:04.272898Z","steps":["trace[745623955] 'agreement among raft nodes before linearized reading' (duration: 162.806676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:32:13.56143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.331936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1881d59-fa84-44e8-bc38-c2a05b7c3eba\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-16T01:32:13.56157Z","caller":"traceutil/trace.go:171","msg":"trace[254057822] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b1881d59-fa84-44e8-bc38-c2a05b7c3eba; range_end:; response_count:1; response_revision:5428; }","duration":"164.56366ms","start":"2026-05-16T01:32:13.396977Z","end":"2026-05-16T01:32:13.561541Z","steps":["trace[254057822] 'range keys from in-memory index tree' (duration: 164.00742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:32:13.815495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.235391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-16T01:32:13.815639Z","caller":"traceutil/trace.go:171","msg":"trace[773756661] linearizableReadLoop","detail":"{readStateIndex:5633; appliedIndex:5632; }","duration":"156.493749ms","start":"2026-05-16T01:32:13.659125Z","end":"2026-05-16T01:32:13.815619Z","steps":["trace[773756661] 'read index received' (duration: 52.788989ms)","trace[773756661] 'applied index is now lower than readState.Index' (duration: 103.70265ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T01:32:13.815811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.694593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-16T01:32:13.815845Z","caller":"traceutil/trace.go:171","msg":"trace[2000067755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5431; }","duration":"156.785405ms","start":"2026-05-16T01:32:13.659051Z","end":"2026-05-16T01:32:13.815836Z","steps":["trace[2000067755] 'agreement among raft nodes before linearized reading' (duration: 156.619081ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:32:13.816012Z","caller":"traceutil/trace.go:171","msg":"trace[937741762] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"160.765544ms","start":"2026-05-16T01:32:13.655199Z","end":"2026-05-16T01:32:13.815965Z","steps":["trace[937741762] 'process raft request' (duration: 56.680346ms)","trace[937741762] 'compare' (duration: 103.026067ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T01:33:26.597553Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3023} {"level":"info","ts":"2026-05-16T01:33:26.686045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3023,"took":"86.262723ms","hash":393273708,"current-db-size-bytes":57909248,"current-db-size":"58 MB","current-db-size-in-use-bytes":31543296,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-16T01:33:26.686148Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":393273708,"revision":3023,"compact-revision":-1} {"level":"info","ts":"2026-05-16T01:38:26.605429Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6110} {"level":"info","ts":"2026-05-16T01:38:26.705616Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6110,"took":"98.147716ms","hash":259138310,"current-db-size-bytes":57909248,"current-db-size":"58 MB","current-db-size-in-use-bytes":23318528,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-16T01:38:26.705719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":259138310,"revision":6110,"compact-revision":3023} {"level":"info","ts":"2026-05-16T01:38:30.440094Z","caller":"traceutil/trace.go:171","msg":"trace[1248962640] transaction","detail":"{read_only:false; response_revision:8320; number_of_response:1; }","duration":"268.041044ms","start":"2026-05-16T01:38:30.172024Z","end":"2026-05-16T01:38:30.440065Z","steps":["trace[1248962640] 'process raft request' (duration: 267.83583ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:38:30.533638Z","caller":"traceutil/trace.go:171","msg":"trace[896568966] transaction","detail":"{read_only:false; response_revision:8321; number_of_response:1; }","duration":"357.778246ms","start":"2026-05-16T01:38:30.17583Z","end":"2026-05-16T01:38:30.533608Z","steps":["trace[896568966] 'process raft request' (duration: 334.048204ms)","trace[896568966] 'compare' (duration: 23.187491ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T01:38:30.53376Z","caller":"traceutil/trace.go:171","msg":"trace[538100544] linearizableReadLoop","detail":"{readStateIndex:8619; appliedIndex:8616; }","duration":"342.694025ms","start":"2026-05-16T01:38:30.191039Z","end":"2026-05-16T01:38:30.533733Z","steps":["trace[538100544] 'read index received' (duration: 248.944269ms)","trace[538100544] 'applied index is now lower than readState.Index' (duration: 93.748346ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T01:38:30.533833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T01:38:30.175812Z","time spent":"357.92606ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T01:38:30.533815Z","caller":"traceutil/trace.go:171","msg":"trace[915351653] transaction","detail":"{read_only:false; response_revision:8322; number_of_response:1; }","duration":"356.428779ms","start":"2026-05-16T01:38:30.177336Z","end":"2026-05-16T01:38:30.533765Z","steps":["trace[915351653] 'process raft request' (duration: 356.210524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:38:30.533997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.953219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-16T01:38:30.534039Z","caller":"traceutil/trace.go:171","msg":"trace[1819406534] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:8322; }","duration":"343.073732ms","start":"2026-05-16T01:38:30.190951Z","end":"2026-05-16T01:38:30.534025Z","steps":["trace[1819406534] 'agreement among raft nodes before linearized reading' (duration: 342.935979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:38:30.534034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T01:38:30.1773Z","time spent":"356.626254ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T01:38:30.534075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T01:38:30.190894Z","time spent":"343.171874ms","remote":"127.0.0.1:32770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":86,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-16T01:38:30.534288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.314482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2psjv\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-16T01:38:30.534341Z","caller":"traceutil/trace.go:171","msg":"trace[1718657725] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2psjv; range_end:; response_count:1; response_revision:8322; }","duration":"272.388925ms","start":"2026-05-16T01:38:30.261938Z","end":"2026-05-16T01:38:30.534327Z","steps":["trace[1718657725] 'agreement among raft nodes before linearized reading' (duration: 272.295813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:38:30.534412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.825481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-16T01:38:30.534444Z","caller":"traceutil/trace.go:171","msg":"trace[1619095535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8322; }","duration":"270.910553ms","start":"2026-05-16T01:38:30.263525Z","end":"2026-05-16T01:38:30.534436Z","steps":["trace[1619095535] 'agreement among raft nodes before linearized reading' (duration: 270.792711ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:38:42.140051Z","caller":"traceutil/trace.go:171","msg":"trace[1711065249] linearizableReadLoop","detail":"{readStateIndex:8680; appliedIndex:8679; }","duration":"173.89832ms","start":"2026-05-16T01:38:41.966119Z","end":"2026-05-16T01:38:42.140018Z","steps":["trace[1711065249] 'read index received' (duration: 165.285331ms)","trace[1711065249] 'applied index is now lower than readState.Index' (duration: 8.611609ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T01:38:42.140345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.197265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-16T01:38:42.140445Z","caller":"traceutil/trace.go:171","msg":"trace[1990354091] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8381; }","duration":"174.37008ms","start":"2026-05-16T01:38:41.966054Z","end":"2026-05-16T01:38:42.140424Z","steps":["trace[1990354091] 'agreement among raft nodes before linearized reading' (duration: 174.081884ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:38:42.140686Z","caller":"traceutil/trace.go:171","msg":"trace[41626353] transaction","detail":"{read_only:false; response_revision:8381; number_of_response:1; }","duration":"256.101746ms","start":"2026-05-16T01:38:41.883994Z","end":"2026-05-16T01:38:42.140096Z","steps":["trace[41626353] 'process raft request' (duration: 247.385416ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:38:42.232401Z","caller":"traceutil/trace.go:171","msg":"trace[835962138] transaction","detail":"{read_only:false; response_revision:8382; number_of_response:1; }","duration":"187.845278ms","start":"2026-05-16T01:38:42.044531Z","end":"2026-05-16T01:38:42.232376Z","steps":["trace[835962138] 'process raft request' (duration: 187.350297ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:38:42.232628Z","caller":"traceutil/trace.go:171","msg":"trace[1720033623] transaction","detail":"{read_only:false; response_revision:8383; number_of_response:1; }","duration":"150.182917ms","start":"2026-05-16T01:38:42.082433Z","end":"2026-05-16T01:38:42.232616Z","steps":["trace[1720033623] 'process raft request' (duration: 149.618505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:38:42.232873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.877495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6d8c59f4d4-rmt9j.18afe808b839aeee\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-05-16T01:38:42.233059Z","caller":"traceutil/trace.go:171","msg":"trace[1990464536] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6d8c59f4d4-rmt9j.18afe808b839aeee; range_end:; response_count:1; response_revision:8383; }","duration":"248.10975ms","start":"2026-05-16T01:38:41.984935Z","end":"2026-05-16T01:38:42.233045Z","steps":["trace[1990464536] 'agreement among raft nodes before linearized reading' (duration: 247.775683ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:38:43.585798Z","caller":"traceutil/trace.go:171","msg":"trace[1225873934] transaction","detail":"{read_only:false; response_revision:8390; number_of_response:1; }","duration":"187.986602ms","start":"2026-05-16T01:38:43.397786Z","end":"2026-05-16T01:38:43.585772Z","steps":["trace[1225873934] 'process raft request' (duration: 187.845669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:38:43.915523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.908004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-16T01:38:43.915613Z","caller":"traceutil/trace.go:171","msg":"trace[556876277] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:8390; }","duration":"221.056967ms","start":"2026-05-16T01:38:43.694538Z","end":"2026-05-16T01:38:43.915595Z","steps":["trace[556876277] 'count revisions from in-memory index tree' (duration: 220.831822ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:39:10.948964Z","caller":"traceutil/trace.go:171","msg":"trace[592514252] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"235.922022ms","start":"2026-05-16T01:39:10.713017Z","end":"2026-05-16T01:39:10.94894Z","steps":["trace[592514252] 'process raft request' (duration: 235.761017ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:39:10.96143Z","caller":"traceutil/trace.go:171","msg":"trace[736692996] linearizableReadLoop","detail":"{readStateIndex:8898; appliedIndex:8896; }","duration":"229.84303ms","start":"2026-05-16T01:39:10.731565Z","end":"2026-05-16T01:39:10.961408Z","steps":["trace[736692996] 'read index received' (duration: 217.267602ms)","trace[736692996] 'applied index is now lower than readState.Index' (duration: 12.573928ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T01:39:10.961527Z","caller":"traceutil/trace.go:171","msg":"trace[1704257691] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"247.18473ms","start":"2026-05-16T01:39:10.714331Z","end":"2026-05-16T01:39:10.961516Z","steps":["trace[1704257691] 'process raft request' (duration: 246.962944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:39:10.961747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.17129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-16T01:39:10.961781Z","caller":"traceutil/trace.go:171","msg":"trace[1253909426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8591; }","duration":"230.241553ms","start":"2026-05-16T01:39:10.731521Z","end":"2026-05-16T01:39:10.961762Z","steps":["trace[1253909426] 'agreement among raft nodes before linearized reading' (duration: 230.119469ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:39:11.138719Z","caller":"traceutil/trace.go:171","msg":"trace[66436080] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"172.86329ms","start":"2026-05-16T01:39:10.965831Z","end":"2026-05-16T01:39:11.138694Z","steps":["trace[66436080] 'process raft request' (duration: 172.760537ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:39:11.138741Z","caller":"traceutil/trace.go:171","msg":"trace[150460582] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"188.746946ms","start":"2026-05-16T01:39:10.94995Z","end":"2026-05-16T01:39:11.138697Z","steps":["trace[150460582] 'process raft request' (duration: 186.106417ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:39:11.138945Z","caller":"traceutil/trace.go:171","msg":"trace[850455866] linearizableReadLoop","detail":"{readStateIndex:8899; appliedIndex:8898; }","duration":"177.444596ms","start":"2026-05-16T01:39:10.96149Z","end":"2026-05-16T01:39:11.138935Z","steps":["trace[850455866] 'read index received' (duration: 174.665743ms)","trace[850455866] 'applied index is now lower than readState.Index' (duration: 2.777803ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T01:39:11.139059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.577435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2psjv\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-16T01:39:11.139085Z","caller":"traceutil/trace.go:171","msg":"trace[63393275] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2psjv; range_end:; response_count:1; response_revision:8593; }","duration":"356.657897ms","start":"2026-05-16T01:39:10.78242Z","end":"2026-05-16T01:39:11.139078Z","steps":["trace[63393275] 'agreement among raft nodes before linearized reading' (duration: 356.548674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:39:11.139103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T01:39:10.782402Z","time spent":"356.696258ms","remote":"127.0.0.1:42758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-2psjv\" "} {"level":"warn","ts":"2026-05-16T01:39:11.139337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.191912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-16T01:39:11.139352Z","caller":"traceutil/trace.go:171","msg":"trace[457591974] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8593; }","duration":"292.235963ms","start":"2026-05-16T01:39:10.847112Z","end":"2026-05-16T01:39:11.139348Z","steps":["trace[457591974] 'agreement among raft nodes before linearized reading' (duration: 292.152731ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:39:14.768598Z","caller":"traceutil/trace.go:171","msg":"trace[1066491855] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"149.715575ms","start":"2026-05-16T01:39:14.618851Z","end":"2026-05-16T01:39:14.768567Z","steps":["trace[1066491855] 'process raft request' (duration: 149.517939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:39:14.895561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.657931ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-16T01:39:24.380785Z","caller":"traceutil/trace.go:171","msg":"trace[1089224994] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"160.826428ms","start":"2026-05-16T01:39:24.219926Z","end":"2026-05-16T01:39:24.380752Z","steps":["trace[1089224994] 'process raft request' (duration: 98.755444ms)","trace[1089224994] 'compare' (duration: 61.940441ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T01:39:24.531858Z","caller":"traceutil/trace.go:171","msg":"trace[81383378] linearizableReadLoop","detail":"{readStateIndex:9014; appliedIndex:9012; }","duration":"234.809982ms","start":"2026-05-16T01:39:24.297022Z","end":"2026-05-16T01:39:24.531832Z","steps":["trace[81383378] 'read index received' (duration: 21.682338ms)","trace[81383378] 'applied index is now lower than readState.Index' (duration: 213.126734ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T01:39:24.532066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.023257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-16T01:39:24.532033Z","caller":"traceutil/trace.go:171","msg":"trace[1314895503] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"306.836687ms","start":"2026-05-16T01:39:24.22516Z","end":"2026-05-16T01:39:24.531996Z","steps":["trace[1314895503] 'process raft request' (duration: 302.395257ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:39:24.532123Z","caller":"traceutil/trace.go:171","msg":"trace[1133531406] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8701; }","duration":"235.15872ms","start":"2026-05-16T01:39:24.29695Z","end":"2026-05-16T01:39:24.532108Z","steps":["trace[1133531406] 'agreement among raft nodes before linearized reading' (duration: 235.015487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:39:24.532253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T01:39:24.225142Z","time spent":"306.989239ms","remote":"127.0.0.1:42758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4632,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T01:39:24.543865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.510542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-16T01:39:24.543936Z","caller":"traceutil/trace.go:171","msg":"trace[956575005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8702; }","duration":"114.635605ms","start":"2026-05-16T01:39:24.429282Z","end":"2026-05-16T01:39:24.543917Z","steps":["trace[956575005] 'agreement among raft nodes before linearized reading' (duration: 114.437641ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:39:25.441156Z","caller":"traceutil/trace.go:171","msg":"trace[1518525979] transaction","detail":"{read_only:false; response_revision:8713; number_of_response:1; }","duration":"103.774866ms","start":"2026-05-16T01:39:25.337348Z","end":"2026-05-16T01:39:25.441123Z","steps":["trace[1518525979] 'process raft request' (duration: 103.577592ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:39:54.864916Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000247a.wal"} {"level":"info","ts":"2026-05-16T01:41:09.526087Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-16T01:41:09.533125Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-16T01:41:09.53323Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-16T01:43:26.612616Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8300} {"level":"info","ts":"2026-05-16T01:43:26.693359Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8300,"took":"77.970059ms","hash":3208559304,"current-db-size-bytes":57909248,"current-db-size":"58 MB","current-db-size-in-use-bytes":24547328,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-16T01:43:26.693442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3208559304,"revision":8300,"compact-revision":6110} {"level":"info","ts":"2026-05-16T01:44:54.932585Z","caller":"traceutil/trace.go:171","msg":"trace[557815295] transaction","detail":"{read_only:false; response_revision:11089; number_of_response:1; }","duration":"129.550646ms","start":"2026-05-16T01:44:54.802984Z","end":"2026-05-16T01:44:54.932534Z","steps":["trace[557815295] 'process raft request' (duration: 129.371922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:46:19.17406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.803161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-16T01:46:19.174362Z","caller":"traceutil/trace.go:171","msg":"trace[833175953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11887; }","duration":"155.097176ms","start":"2026-05-16T01:46:19.019148Z","end":"2026-05-16T01:46:19.174245Z","steps":["trace[833175953] 'range keys from in-memory index tree' (duration: 154.687488ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:48:26.619547Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10527} {"level":"info","ts":"2026-05-16T01:48:26.698215Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10527,"took":"75.83135ms","hash":3163160159,"current-db-size-bytes":57909248,"current-db-size":"58 MB","current-db-size-in-use-bytes":25681920,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-16T01:48:26.698305Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3163160159,"revision":10527,"compact-revision":8300} {"level":"warn","ts":"2026-05-16T01:53:00.384211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.591985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-16T01:53:00.384404Z","caller":"traceutil/trace.go:171","msg":"trace[2038246932] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:14558; }","duration":"108.877671ms","start":"2026-05-16T01:53:00.275498Z","end":"2026-05-16T01:53:00.384375Z","steps":["trace[2038246932] 'count revisions from in-memory index tree' (duration: 108.480853ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:53:26.62698Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12751} {"level":"info","ts":"2026-05-16T01:53:26.70751Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12751,"took":"76.961015ms","hash":861812526,"current-db-size-bytes":57909248,"current-db-size":"58 MB","current-db-size-in-use-bytes":27914240,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-16T01:53:26.707603Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":861812526,"revision":12751,"compact-revision":10527} {"level":"info","ts":"2026-05-16T01:54:34.336213Z","caller":"traceutil/trace.go:171","msg":"trace[2048693431] linearizableReadLoop","detail":"{readStateIndex:16144; appliedIndex:16143; }","duration":"110.356229ms","start":"2026-05-16T01:54:34.225832Z","end":"2026-05-16T01:54:34.336188Z","steps":["trace[2048693431] 'read index received' (duration: 110.270257ms)","trace[2048693431] 'applied index is now lower than readState.Index' (duration: 84.382µs)"],"step_count":2} {"level":"info","ts":"2026-05-16T01:54:34.336268Z","caller":"traceutil/trace.go:171","msg":"trace[243269294] transaction","detail":"{read_only:false; response_revision:15602; number_of_response:1; }","duration":"111.521272ms","start":"2026-05-16T01:54:34.22471Z","end":"2026-05-16T01:54:34.336231Z","steps":["trace[243269294] 'process raft request' (duration: 111.309908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:54:34.336571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.693556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-05-16T01:54:34.336679Z","caller":"traceutil/trace.go:171","msg":"trace[440403050] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15602; }","duration":"110.838309ms","start":"2026-05-16T01:54:34.225818Z","end":"2026-05-16T01:54:34.336657Z","steps":["trace[440403050] 'agreement among raft nodes before linearized reading' (duration: 110.484131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:54:34.337202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.259758ms","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-05-16T01:54:34.337266Z","caller":"traceutil/trace.go:171","msg":"trace[568338209] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15604; }","duration":"111.342389ms","start":"2026-05-16T01:54:34.22591Z","end":"2026-05-16T01:54:34.337253Z","steps":["trace[568338209] 'agreement among raft nodes before linearized reading' (duration: 111.141235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:54:34.337553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.866709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-16T01:54:34.337628Z","caller":"traceutil/trace.go:171","msg":"trace[1700094973] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15604; }","duration":"109.951581ms","start":"2026-05-16T01:54:34.227662Z","end":"2026-05-16T01:54:34.337614Z","steps":["trace[1700094973] 'agreement among raft nodes before linearized reading' (duration: 109.776857ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:54:39.880675Z","caller":"traceutil/trace.go:171","msg":"trace[568693253] linearizableReadLoop","detail":"{readStateIndex:16174; appliedIndex:16173; }","duration":"162.230146ms","start":"2026-05-16T01:54:39.718416Z","end":"2026-05-16T01:54:39.880646Z","steps":["trace[568693253] 'read index received' (duration: 68.208397ms)","trace[568693253] 'applied index is now lower than readState.Index' (duration: 94.020079ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T01:54:39.880762Z","caller":"traceutil/trace.go:171","msg":"trace[735312552] transaction","detail":"{read_only:false; response_revision:15630; number_of_response:1; }","duration":"260.238405ms","start":"2026-05-16T01:54:39.620466Z","end":"2026-05-16T01:54:39.880705Z","steps":["trace[735312552] 'process raft request' (duration: 166.260777ms)","trace[735312552] 'compare' (duration: 93.720513ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T01:54:39.881042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.280605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8xwh5\" ","response":"range_response_count:1 size:22100"} {"level":"warn","ts":"2026-05-16T01:54:39.881042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.602873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8xwh5\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-05-16T01:54:39.881097Z","caller":"traceutil/trace.go:171","msg":"trace[674048049] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8xwh5; range_end:; response_count:1; response_revision:15630; }","duration":"150.399848ms","start":"2026-05-16T01:54:39.73068Z","end":"2026-05-16T01:54:39.88108Z","steps":["trace[674048049] 'agreement among raft nodes before linearized reading' (duration: 150.170523ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:54:39.881106Z","caller":"traceutil/trace.go:171","msg":"trace[871663026] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8xwh5; range_end:; response_count:1; response_revision:15630; }","duration":"162.706766ms","start":"2026-05-16T01:54:39.718382Z","end":"2026-05-16T01:54:39.881089Z","steps":["trace[871663026] 'agreement among raft nodes before linearized reading' (duration: 162.41053ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:54:41.072271Z","caller":"traceutil/trace.go:171","msg":"trace[1229902567] transaction","detail":"{read_only:false; response_revision:15636; number_of_response:1; }","duration":"216.536183ms","start":"2026-05-16T01:54:40.85571Z","end":"2026-05-16T01:54:41.072247Z","steps":["trace[1229902567] 'process raft request' (duration: 216.40861ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:54:41.073465Z","caller":"traceutil/trace.go:171","msg":"trace[1215406496] linearizableReadLoop","detail":"{readStateIndex:16181; appliedIndex:16179; }","duration":"188.747503ms","start":"2026-05-16T01:54:40.884694Z","end":"2026-05-16T01:54:41.073442Z","steps":["trace[1215406496] 'read index received' (duration: 187.511597ms)","trace[1215406496] 'applied index is now lower than readState.Index' (duration: 1.234626ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T01:54:41.073775Z","caller":"traceutil/trace.go:171","msg":"trace[156114154] transaction","detail":"{read_only:false; response_revision:15637; number_of_response:1; }","duration":"215.782888ms","start":"2026-05-16T01:54:40.857974Z","end":"2026-05-16T01:54:41.073757Z","steps":["trace[156114154] 'process raft request' (duration: 215.356809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:54:41.074103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.389155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2psjv\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-16T01:54:41.074157Z","caller":"traceutil/trace.go:171","msg":"trace[1976907642] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2psjv; range_end:; response_count:1; response_revision:15637; }","duration":"189.490508ms","start":"2026-05-16T01:54:40.884654Z","end":"2026-05-16T01:54:41.074144Z","steps":["trace[1976907642] 'agreement among raft nodes before linearized reading' (duration: 189.383575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:54:41.074504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.35079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-16T01:54:41.07455Z","caller":"traceutil/trace.go:171","msg":"trace[47250560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15637; }","duration":"171.419991ms","start":"2026-05-16T01:54:40.90312Z","end":"2026-05-16T01:54:41.07454Z","steps":["trace[47250560] 'agreement among raft nodes before linearized reading' (duration: 171.271108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T01:54:41.075309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.494872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-16T01:54:41.075376Z","caller":"traceutil/trace.go:171","msg":"trace[2089404600] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:15637; }","duration":"119.609215ms","start":"2026-05-16T01:54:40.95575Z","end":"2026-05-16T01:54:41.07536Z","steps":["trace[2089404600] 'agreement among raft nodes before linearized reading' (duration: 119.307889ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T01:58:26.632258Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14901} {"level":"info","ts":"2026-05-16T01:58:26.719768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14901,"took":"83.943383ms","hash":3456959750,"current-db-size-bytes":57909248,"current-db-size":"58 MB","current-db-size-in-use-bytes":30404608,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-16T01:58:26.719862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3456959750,"revision":14901,"compact-revision":12751} {"level":"info","ts":"2026-05-16T02:00:58.299241Z","caller":"traceutil/trace.go:171","msg":"trace[1257283912] transaction","detail":"{read_only:false; response_revision:18500; number_of_response:1; }","duration":"126.954904ms","start":"2026-05-16T02:00:58.172251Z","end":"2026-05-16T02:00:58.299206Z","steps":["trace[1257283912] 'process raft request' (duration: 126.796031ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:00:58.844604Z","caller":"traceutil/trace.go:171","msg":"trace[268061744] transaction","detail":"{read_only:false; response_revision:18501; number_of_response:1; }","duration":"370.251125ms","start":"2026-05-16T02:00:58.474324Z","end":"2026-05-16T02:00:58.844575Z","steps":["trace[268061744] 'process raft request' (duration: 370.087702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:00:58.844785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:00:58.474308Z","time spent":"370.388948ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:00:58.921529Z","caller":"traceutil/trace.go:171","msg":"trace[2136641632] transaction","detail":"{read_only:false; response_revision:18502; number_of_response:1; }","duration":"422.878937ms","start":"2026-05-16T02:00:58.498609Z","end":"2026-05-16T02:00:58.921488Z","steps":["trace[2136641632] 'process raft request' (duration: 409.393665ms)","trace[2136641632] 'compare' (duration: 13.088965ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:00:58.921741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:00:58.498579Z","time spent":"423.056241ms","remote":"127.0.0.1:42760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7731,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:00:58.921937Z","caller":"traceutil/trace.go:171","msg":"trace[740686405] transaction","detail":"{read_only:false; response_revision:18503; number_of_response:1; }","duration":"408.503158ms","start":"2026-05-16T02:00:58.513403Z","end":"2026-05-16T02:00:58.921906Z","steps":["trace[740686405] 'process raft request' (duration: 407.986407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:00:58.922162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:00:58.513386Z","time spent":"408.63737ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:00:58.922534Z","caller":"traceutil/trace.go:171","msg":"trace[1608246234] transaction","detail":"{read_only:false; response_revision:18504; number_of_response:1; }","duration":"335.010513ms","start":"2026-05-16T02:00:58.587509Z","end":"2026-05-16T02:00:58.922519Z","steps":["trace[1608246234] 'process raft request' (duration: 334.253608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:00:58.922622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:00:58.587493Z","time spent":"335.086604ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:00:58.922729Z","caller":"traceutil/trace.go:171","msg":"trace[1356498462] linearizableReadLoop","detail":"{readStateIndex:19138; appliedIndex:19135; }","duration":"336.113056ms","start":"2026-05-16T02:00:58.586594Z","end":"2026-05-16T02:00:58.922707Z","steps":["trace[1356498462] 'read index received' (duration: 257.749304ms)","trace[1356498462] 'applied index is now lower than readState.Index' (duration: 78.362092ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:00:58.92279Z","caller":"traceutil/trace.go:171","msg":"trace[1290062919] transaction","detail":"{read_only:false; response_revision:18505; number_of_response:1; }","duration":"324.43401ms","start":"2026-05-16T02:00:58.59834Z","end":"2026-05-16T02:00:58.922774Z","steps":["trace[1290062919] 'process raft request' (duration: 324.136884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:00:58.922872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:00:58.598322Z","time spent":"324.507102ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T02:00:58.92305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.271807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-16T02:00:58.923102Z","caller":"traceutil/trace.go:171","msg":"trace[701659405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18505; }","duration":"325.350788ms","start":"2026-05-16T02:00:58.597735Z","end":"2026-05-16T02:00:58.923086Z","steps":["trace[701659405] 'agreement among raft nodes before linearized reading' (duration: 325.208585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:00:58.923138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:00:58.597722Z","time spent":"325.4096ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-16T02:00:58.923271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.646827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-16T02:00:58.923315Z","caller":"traceutil/trace.go:171","msg":"trace[1471309325] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:18505; }","duration":"336.768169ms","start":"2026-05-16T02:00:58.586536Z","end":"2026-05-16T02:00:58.923304Z","steps":["trace[1471309325] 'agreement among raft nodes before linearized reading' (duration: 336.647676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:00:58.923385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:00:58.586511Z","time spent":"336.83352ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-16T02:00:58.92347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.120468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8xwh5\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-05-16T02:00:58.923553Z","caller":"traceutil/trace.go:171","msg":"trace[147501573] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8xwh5; range_end:; response_count:1; response_revision:18505; }","duration":"239.26445ms","start":"2026-05-16T02:00:58.684276Z","end":"2026-05-16T02:00:58.92354Z","steps":["trace[147501573] 'agreement among raft nodes before linearized reading' (duration: 239.014495ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:02:24.97663Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-16T02:02:24.98188Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-16T02:02:24.982009Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-16T02:03:26.640051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17366} {"level":"info","ts":"2026-05-16T02:03:26.736876Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17366,"took":"92.59968ms","hash":842021461,"current-db-size-bytes":57909248,"current-db-size":"58 MB","current-db-size-in-use-bytes":34406400,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-16T02:03:26.736967Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":842021461,"revision":17366,"compact-revision":14901} {"level":"info","ts":"2026-05-16T02:06:45.607176Z","caller":"traceutil/trace.go:171","msg":"trace[530546107] linearizableReadLoop","detail":"{readStateIndex:21892; appliedIndex:21891; }","duration":"119.623006ms","start":"2026-05-16T02:06:45.487524Z","end":"2026-05-16T02:06:45.607147Z","steps":["trace[530546107] 'read index received' (duration: 119.432882ms)","trace[530546107] 'applied index is now lower than readState.Index' (duration: 186.134µs)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:06:45.607323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.767939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-16T02:06:45.607348Z","caller":"traceutil/trace.go:171","msg":"trace[126681370] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21166; }","duration":"119.860411ms","start":"2026-05-16T02:06:45.487479Z","end":"2026-05-16T02:06:45.60734Z","steps":["trace[126681370] 'agreement among raft nodes before linearized reading' (duration: 119.779019ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:06:45.607432Z","caller":"traceutil/trace.go:171","msg":"trace[2053172916] transaction","detail":"{read_only:false; response_revision:21166; number_of_response:1; }","duration":"150.546561ms","start":"2026-05-16T02:06:45.456851Z","end":"2026-05-16T02:06:45.607397Z","steps":["trace[2053172916] 'process raft request' (duration: 150.136943ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:06:46.234158Z","caller":"traceutil/trace.go:171","msg":"trace[447177802] transaction","detail":"{read_only:false; response_revision:21167; number_of_response:1; }","duration":"117.817129ms","start":"2026-05-16T02:06:46.116307Z","end":"2026-05-16T02:06:46.234124Z","steps":["trace[447177802] 'process raft request' (duration: 117.612575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:06:46.514058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.926698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-16T02:06:46.514297Z","caller":"traceutil/trace.go:171","msg":"trace[1826456716] linearizableReadLoop","detail":"{readStateIndex:21895; appliedIndex:21893; }","duration":"187.179849ms","start":"2026-05-16T02:06:46.327099Z","end":"2026-05-16T02:06:46.514279Z","steps":["trace[1826456716] 'read index received' (duration: 33.802773ms)","trace[1826456716] 'applied index is now lower than readState.Index' (duration: 153.375916ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:06:46.5145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.401095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-16T02:06:46.51454Z","caller":"traceutil/trace.go:171","msg":"trace[437875283] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:21169; }","duration":"187.495976ms","start":"2026-05-16T02:06:46.327032Z","end":"2026-05-16T02:06:46.514528Z","steps":["trace[437875283] 'agreement among raft nodes before linearized reading' (duration: 187.305353ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:06:46.514529Z","caller":"traceutil/trace.go:171","msg":"trace[61733234] transaction","detail":"{read_only:false; response_revision:21168; number_of_response:1; }","duration":"214.316699ms","start":"2026-05-16T02:06:46.300169Z","end":"2026-05-16T02:06:46.514485Z","steps":["trace[61733234] 'process raft request' (duration: 60.761627ms)","trace[61733234] 'compare' (duration: 152.751205ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:06:46.514613Z","caller":"traceutil/trace.go:171","msg":"trace[427639747] transaction","detail":"{read_only:false; response_revision:21169; number_of_response:1; }","duration":"212.955742ms","start":"2026-05-16T02:06:46.301583Z","end":"2026-05-16T02:06:46.514538Z","steps":["trace[427639747] 'process raft request' (duration: 212.626174ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:06:47.059035Z","caller":"traceutil/trace.go:171","msg":"trace[503222780] linearizableReadLoop","detail":"{readStateIndex:21897; appliedIndex:21896; }","duration":"168.961463ms","start":"2026-05-16T02:06:46.890034Z","end":"2026-05-16T02:06:47.058996Z","steps":["trace[503222780] 'read index received' (duration: 168.742988ms)","trace[503222780] 'applied index is now lower than readState.Index' (duration: 216.485µs)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:06:47.059321Z","caller":"traceutil/trace.go:171","msg":"trace[1397525360] transaction","detail":"{read_only:false; response_revision:21171; number_of_response:1; }","duration":"197.641362ms","start":"2026-05-16T02:06:46.861572Z","end":"2026-05-16T02:06:47.059214Z","steps":["trace[1397525360] 'process raft request' (duration: 197.197893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:06:47.059397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.3423ms","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-05-16T02:06:47.059462Z","caller":"traceutil/trace.go:171","msg":"trace[797246964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21171; }","duration":"169.462643ms","start":"2026-05-16T02:06:46.88998Z","end":"2026-05-16T02:06:47.059443Z","steps":["trace[797246964] 'agreement among raft nodes before linearized reading' (duration: 169.200087ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:06:47.180328Z","caller":"traceutil/trace.go:171","msg":"trace[668621368] linearizableReadLoop","detail":"{readStateIndex:21898; appliedIndex:21897; }","duration":"121.149736ms","start":"2026-05-16T02:06:47.059148Z","end":"2026-05-16T02:06:47.180297Z","steps":["trace[668621368] 'read index received' (duration: 115.166226ms)","trace[668621368] 'applied index is now lower than readState.Index' (duration: 5.98191ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:06:47.180517Z","caller":"traceutil/trace.go:171","msg":"trace[125797446] transaction","detail":"{read_only:false; response_revision:21172; number_of_response:1; }","duration":"278.727629ms","start":"2026-05-16T02:06:46.901776Z","end":"2026-05-16T02:06:47.180504Z","steps":["trace[125797446] 'process raft request' (duration: 272.518874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:06:47.180811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.634978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-16T02:06:47.180869Z","caller":"traceutil/trace.go:171","msg":"trace[1771320558] transaction","detail":"{read_only:false; response_revision:21173; number_of_response:1; }","duration":"116.031443ms","start":"2026-05-16T02:06:47.064807Z","end":"2026-05-16T02:06:47.180838Z","steps":["trace[1771320558] 'process raft request' (duration: 115.429541ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:06:47.18091Z","caller":"traceutil/trace.go:171","msg":"trace[1053837098] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:21174; }","duration":"226.793831ms","start":"2026-05-16T02:06:46.954091Z","end":"2026-05-16T02:06:47.180885Z","steps":["trace[1053837098] 'agreement among raft nodes before linearized reading' (duration: 226.559406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:06:47.181035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.922858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-16T02:06:47.18113Z","caller":"traceutil/trace.go:171","msg":"trace[48729358] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:21174; }","duration":"203.093203ms","start":"2026-05-16T02:06:46.978016Z","end":"2026-05-16T02:06:47.181109Z","steps":["trace[48729358] 'agreement among raft nodes before linearized reading' (duration: 202.810606ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:08:26.646722Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19738} {"level":"info","ts":"2026-05-16T02:08:26.761769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19738,"took":"110.646042ms","hash":13940329,"current-db-size-bytes":57909248,"current-db-size":"58 MB","current-db-size-in-use-bytes":30273536,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-16T02:08:26.761878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":13940329,"revision":19738,"compact-revision":17366} {"level":"info","ts":"2026-05-16T02:10:44.666127Z","caller":"traceutil/trace.go:171","msg":"trace[2128334296] transaction","detail":"{read_only:false; response_revision:23640; number_of_response:1; }","duration":"162.419186ms","start":"2026-05-16T02:10:44.503673Z","end":"2026-05-16T02:10:44.666092Z","steps":["trace[2128334296] 'process raft request' (duration: 162.245892ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:10:44.772538Z","caller":"traceutil/trace.go:171","msg":"trace[2140300641] transaction","detail":"{read_only:false; response_revision:23641; number_of_response:1; }","duration":"266.222365ms","start":"2026-05-16T02:10:44.506286Z","end":"2026-05-16T02:10:44.772508Z","steps":["trace[2140300641] 'process raft request' (duration: 263.840557ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:10:44.772702Z","caller":"traceutil/trace.go:171","msg":"trace[449282126] transaction","detail":"{read_only:false; response_revision:23642; number_of_response:1; }","duration":"216.293885ms","start":"2026-05-16T02:10:44.556387Z","end":"2026-05-16T02:10:44.772681Z","steps":["trace[449282126] 'process raft request' (duration: 216.03271ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:10:44.772721Z","caller":"traceutil/trace.go:171","msg":"trace[1226621791] linearizableReadLoop","detail":"{readStateIndex:24440; appliedIndex:24438; }","duration":"264.275145ms","start":"2026-05-16T02:10:44.508379Z","end":"2026-05-16T02:10:44.772654Z","steps":["trace[1226621791] 'read index received' (duration: 157.527086ms)","trace[1226621791] 'applied index is now lower than readState.Index' (duration: 106.744829ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:10:44.773057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.646343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-16T02:10:44.773213Z","caller":"traceutil/trace.go:171","msg":"trace[1107643370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23642; }","duration":"264.764365ms","start":"2026-05-16T02:10:44.508342Z","end":"2026-05-16T02:10:44.773106Z","steps":["trace[1107643370] 'agreement among raft nodes before linearized reading' (duration: 264.484129ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:10:44.947558Z","caller":"traceutil/trace.go:171","msg":"trace[677526807] transaction","detail":"{read_only:false; response_revision:23644; number_of_response:1; }","duration":"101.383652ms","start":"2026-05-16T02:10:44.846149Z","end":"2026-05-16T02:10:44.947533Z","steps":["trace[677526807] 'process raft request' (duration: 101.264159ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:10:44.947506Z","caller":"traceutil/trace.go:171","msg":"trace[352064173] transaction","detail":"{read_only:false; response_revision:23643; number_of_response:1; }","duration":"167.432777ms","start":"2026-05-16T02:10:44.78001Z","end":"2026-05-16T02:10:44.947443Z","steps":["trace[352064173] 'process raft request' (duration: 133.97624ms)","trace[352064173] 'compare' (duration: 32.983967ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:10:44.947592Z","caller":"traceutil/trace.go:171","msg":"trace[1027257294] linearizableReadLoop","detail":"{readStateIndex:24442; appliedIndex:24441; }","duration":"156.580477ms","start":"2026-05-16T02:10:44.790928Z","end":"2026-05-16T02:10:44.947508Z","steps":["trace[1027257294] 'read index received' (duration: 122.963548ms)","trace[1027257294] 'applied index is now lower than readState.Index' (duration: 33.610799ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:10:44.948618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.648208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager\" ","response":"range_response_count:1 size:5023"} {"level":"info","ts":"2026-05-16T02:10:44.948914Z","caller":"traceutil/trace.go:171","msg":"trace[791586856] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager; range_end:; response_count:1; response_revision:23644; }","duration":"157.910104ms","start":"2026-05-16T02:10:44.790879Z","end":"2026-05-16T02:10:44.948789Z","steps":["trace[791586856] 'agreement among raft nodes before linearized reading' (duration: 156.921034ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:13:26.654845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22011} {"level":"info","ts":"2026-05-16T02:13:26.770039Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22011,"took":"111.317204ms","hash":91202148,"current-db-size-bytes":57909248,"current-db-size":"58 MB","current-db-size-in-use-bytes":43606016,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-05-16T02:13:26.770103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":91202148,"revision":22011,"compact-revision":19738} {"level":"info","ts":"2026-05-16T02:14:05.02914Z","caller":"traceutil/trace.go:171","msg":"trace[394333807] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"201.328916ms","start":"2026-05-16T02:14:04.827778Z","end":"2026-05-16T02:14:05.029107Z","steps":["trace[394333807] 'process raft request' (duration: 201.102891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:05.383725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.238755ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-16T02:14:05.384067Z","caller":"traceutil/trace.go:171","msg":"trace[691455576] linearizableReadLoop","detail":"{readStateIndex:26506; appliedIndex:26504; }","duration":"257.234099ms","start":"2026-05-16T02:14:05.126812Z","end":"2026-05-16T02:14:05.384046Z","steps":["trace[691455576] 'read index received' (duration: 97.700178ms)","trace[691455576] 'applied index is now lower than readState.Index' (duration: 159.533121ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:05.38412Z","caller":"traceutil/trace.go:171","msg":"trace[1362364368] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"464.056395ms","start":"2026-05-16T02:14:04.920026Z","end":"2026-05-16T02:14:05.384082Z","steps":["trace[1362364368] 'process raft request' (duration: 463.8098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:05.384307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:04.919994Z","time spent":"464.215409ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:05.384356Z","caller":"traceutil/trace.go:171","msg":"trace[1594270623] transaction","detail":"{read_only:false; response_revision:25655; number_of_response:1; }","duration":"130.973641ms","start":"2026-05-16T02:14:05.253351Z","end":"2026-05-16T02:14:05.384325Z","steps":["trace[1594270623] 'process raft request' (duration: 130.652355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:05.386767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.910242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:05.38693Z","caller":"traceutil/trace.go:171","msg":"trace[459151229] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:25655; }","duration":"260.129267ms","start":"2026-05-16T02:14:05.126746Z","end":"2026-05-16T02:14:05.386875Z","steps":["trace[459151229] 'agreement among raft nodes before linearized reading' (duration: 257.367151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:05.387367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.305336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:05.387456Z","caller":"traceutil/trace.go:171","msg":"trace[1990187105] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25656; }","duration":"242.288566ms","start":"2026-05-16T02:14:05.145146Z","end":"2026-05-16T02:14:05.387435Z","steps":["trace[1990187105] 'agreement among raft nodes before linearized reading' (duration: 239.283205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:05.387874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.56099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-16T02:14:05.387885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.740104ms","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-05-16T02:14:05.38797Z","caller":"traceutil/trace.go:171","msg":"trace[334895032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25656; }","duration":"196.821526ms","start":"2026-05-16T02:14:05.191126Z","end":"2026-05-16T02:14:05.387947Z","steps":["trace[334895032] 'agreement among raft nodes before linearized reading' (duration: 193.397896ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:05.387973Z","caller":"traceutil/trace.go:171","msg":"trace[1786735100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25656; }","duration":"240.99453ms","start":"2026-05-16T02:14:05.146934Z","end":"2026-05-16T02:14:05.387929Z","steps":["trace[1786735100] 'agreement among raft nodes before linearized reading' (duration: 237.59699ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:05.517277Z","caller":"traceutil/trace.go:171","msg":"trace[820723461] transaction","detail":"{read_only:false; response_revision:25657; number_of_response:1; }","duration":"121.918029ms","start":"2026-05-16T02:14:05.395326Z","end":"2026-05-16T02:14:05.517244Z","steps":["trace[820723461] 'process raft request' (duration: 110.250493ms)","trace[820723461] 'compare' (duration: 11.478222ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:05.517579Z","caller":"traceutil/trace.go:171","msg":"trace[479864888] transaction","detail":"{read_only:false; response_revision:25658; number_of_response:1; }","duration":"117.212903ms","start":"2026-05-16T02:14:05.400295Z","end":"2026-05-16T02:14:05.517508Z","steps":["trace[479864888] 'process raft request' (duration: 116.893917ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:05.708723Z","caller":"traceutil/trace.go:171","msg":"trace[338511335] transaction","detail":"{read_only:false; response_revision:25659; number_of_response:1; }","duration":"141.071076ms","start":"2026-05-16T02:14:05.567623Z","end":"2026-05-16T02:14:05.708694Z","steps":["trace[338511335] 'process raft request' (duration: 112.044488ms)","trace[338511335] 'compare' (duration: 28.879265ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:05.938269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.335437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-16T02:14:05.938377Z","caller":"traceutil/trace.go:171","msg":"trace[171049842] linearizableReadLoop","detail":"{readStateIndex:26512; appliedIndex:26511; }","duration":"169.125354ms","start":"2026-05-16T02:14:05.769234Z","end":"2026-05-16T02:14:05.938359Z","steps":["trace[171049842] 'read index received' (duration: 46.508011ms)","trace[171049842] 'applied index is now lower than readState.Index' (duration: 122.616053ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:05.93855Z","caller":"traceutil/trace.go:171","msg":"trace[1675596682] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"203.440689ms","start":"2026-05-16T02:14:05.73507Z","end":"2026-05-16T02:14:05.93851Z","steps":["trace[1675596682] 'process raft request' (duration: 80.757335ms)","trace[1675596682] 'compare' (duration: 121.938949ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:05.938829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.552742ms","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-05-16T02:14:05.938929Z","caller":"traceutil/trace.go:171","msg":"trace[212318625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25660; }","duration":"169.711186ms","start":"2026-05-16T02:14:05.769192Z","end":"2026-05-16T02:14:05.938903Z","steps":["trace[212318625] 'agreement among raft nodes before linearized reading' (duration: 169.340518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:06.215073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.66764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-16T02:14:06.215202Z","caller":"traceutil/trace.go:171","msg":"trace[1136977411] linearizableReadLoop","detail":"{readStateIndex:26514; appliedIndex:26513; }","duration":"146.828053ms","start":"2026-05-16T02:14:06.068354Z","end":"2026-05-16T02:14:06.215182Z","steps":["trace[1136977411] 'read index received' (duration: 944.139µs)","trace[1136977411] 'applied index is now lower than readState.Index' (duration: 145.882604ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:06.215279Z","caller":"traceutil/trace.go:171","msg":"trace[371717853] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"270.653001ms","start":"2026-05-16T02:14:05.944612Z","end":"2026-05-16T02:14:06.215265Z","steps":["trace[371717853] 'process raft request' (duration: 124.681125ms)","trace[371717853] 'compare' (duration: 145.514467ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:06.215544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.161381ms","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-05-16T02:14:06.215873Z","caller":"traceutil/trace.go:171","msg":"trace[780731367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25662; }","duration":"147.531848ms","start":"2026-05-16T02:14:06.068294Z","end":"2026-05-16T02:14:06.215826Z","steps":["trace[780731367] 'agreement among raft nodes before linearized reading' (duration: 146.995757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:06.244817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.154296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-16T02:14:06.244917Z","caller":"traceutil/trace.go:171","msg":"trace[1569541030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25663; }","duration":"174.353341ms","start":"2026-05-16T02:14:06.070541Z","end":"2026-05-16T02:14:06.244894Z","steps":["trace[1569541030] 'agreement among raft nodes before linearized reading' (duration: 174.029784ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:06.360649Z","caller":"traceutil/trace.go:171","msg":"trace[1043822730] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"140.006574ms","start":"2026-05-16T02:14:06.220606Z","end":"2026-05-16T02:14:06.360612Z","steps":["trace[1043822730] 'process raft request' (duration: 139.814401ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:06.373247Z","caller":"traceutil/trace.go:171","msg":"trace[248055392] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"123.224435ms","start":"2026-05-16T02:14:06.249997Z","end":"2026-05-16T02:14:06.373221Z","steps":["trace[248055392] 'process raft request' (duration: 123.036612ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:06.588735Z","caller":"traceutil/trace.go:171","msg":"trace[2115347523] linearizableReadLoop","detail":"{readStateIndex:26519; appliedIndex:26518; }","duration":"128.796467ms","start":"2026-05-16T02:14:06.459897Z","end":"2026-05-16T02:14:06.588694Z","steps":["trace[2115347523] 'read index received' (duration: 83.200444ms)","trace[2115347523] 'applied index is now lower than readState.Index' (duration: 45.594773ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:06.588957Z","caller":"traceutil/trace.go:171","msg":"trace[1726553429] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"177.014604ms","start":"2026-05-16T02:14:06.411707Z","end":"2026-05-16T02:14:06.588721Z","steps":["trace[1726553429] 'process raft request' (duration: 131.420961ms)","trace[1726553429] 'compare' (duration: 45.363188ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:06.588995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.083794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-05-16T02:14:06.58922Z","caller":"traceutil/trace.go:171","msg":"trace[1698034836] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25667; }","duration":"129.359699ms","start":"2026-05-16T02:14:06.459836Z","end":"2026-05-16T02:14:06.589196Z","steps":["trace[1698034836] 'agreement among raft nodes before linearized reading' (duration: 128.984072ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:07.774585Z","caller":"traceutil/trace.go:171","msg":"trace[1199203560] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"373.88413ms","start":"2026-05-16T02:14:07.400671Z","end":"2026-05-16T02:14:07.774555Z","steps":["trace[1199203560] 'process raft request' (duration: 341.351132ms)","trace[1199203560] 'compare' (duration: 32.141209ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:07.774728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:07.400641Z","time spent":"374.011113ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:07.774738Z","caller":"traceutil/trace.go:171","msg":"trace[1224088246] linearizableReadLoop","detail":"{readStateIndex:26522; appliedIndex:26521; }","duration":"252.568124ms","start":"2026-05-16T02:14:07.522143Z","end":"2026-05-16T02:14:07.774711Z","steps":["trace[1224088246] 'read index received' (duration: 219.892303ms)","trace[1224088246] 'applied index is now lower than readState.Index' (duration: 32.674141ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:07.775124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.02475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-16T02:14:07.775151Z","caller":"traceutil/trace.go:171","msg":"trace[159064518] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25671; }","duration":"204.088612ms","start":"2026-05-16T02:14:07.571056Z","end":"2026-05-16T02:14:07.775144Z","steps":["trace[159064518] 'agreement among raft nodes before linearized reading' (duration: 203.959649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:07.775263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.092046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-16T02:14:07.775291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.229209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:07.775356Z","caller":"traceutil/trace.go:171","msg":"trace[390267344] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25671; }","duration":"181.362922ms","start":"2026-05-16T02:14:07.593978Z","end":"2026-05-16T02:14:07.775341Z","steps":["trace[390267344] 'agreement among raft nodes before linearized reading' (duration: 181.239109ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:07.775368Z","caller":"traceutil/trace.go:171","msg":"trace[516160977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25671; }","duration":"253.209358ms","start":"2026-05-16T02:14:07.522127Z","end":"2026-05-16T02:14:07.775337Z","steps":["trace[516160977] 'agreement among raft nodes before linearized reading' (duration: 252.926432ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:07.924495Z","caller":"traceutil/trace.go:171","msg":"trace[673308564] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"131.38518ms","start":"2026-05-16T02:14:07.793071Z","end":"2026-05-16T02:14:07.924456Z","steps":["trace[673308564] 'process raft request' (duration: 126.588983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:13.453222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.036772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-16T02:14:13.453348Z","caller":"traceutil/trace.go:171","msg":"trace[561904574] linearizableReadLoop","detail":"{readStateIndex:26579; appliedIndex:26578; }","duration":"294.649635ms","start":"2026-05-16T02:14:13.158676Z","end":"2026-05-16T02:14:13.453326Z","steps":["trace[561904574] 'read index received' (duration: 170.249796ms)","trace[561904574] 'applied index is now lower than readState.Index' (duration: 124.398339ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:13.45339Z","caller":"traceutil/trace.go:171","msg":"trace[837610287] transaction","detail":"{read_only:false; response_revision:25726; number_of_response:1; }","duration":"303.101787ms","start":"2026-05-16T02:14:13.150251Z","end":"2026-05-16T02:14:13.453353Z","steps":["trace[837610287] 'process raft request' (duration: 178.747188ms)","trace[837610287] 'compare' (duration: 123.875569ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:13.453486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.801799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:13.453523Z","caller":"traceutil/trace.go:171","msg":"trace[1546859899] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25726; }","duration":"294.875161ms","start":"2026-05-16T02:14:13.158633Z","end":"2026-05-16T02:14:13.453508Z","steps":["trace[1546859899] 'agreement among raft nodes before linearized reading' (duration: 294.804079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:13.453558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:13.150223Z","time spent":"303.237091ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T02:14:13.453856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.179623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:13.453919Z","caller":"traceutil/trace.go:171","msg":"trace[433286671] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25726; }","duration":"162.294786ms","start":"2026-05-16T02:14:13.291612Z","end":"2026-05-16T02:14:13.453907Z","steps":["trace[433286671] 'agreement among raft nodes before linearized reading' (duration: 162.169503ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:13.716975Z","caller":"traceutil/trace.go:171","msg":"trace[2136548178] transaction","detail":"{read_only:false; response_revision:25727; number_of_response:1; }","duration":"243.4556ms","start":"2026-05-16T02:14:13.473485Z","end":"2026-05-16T02:14:13.716941Z","steps":["trace[2136548178] 'process raft request' (duration: 238.348926ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:13.89187Z","caller":"traceutil/trace.go:171","msg":"trace[546609009] transaction","detail":"{read_only:false; response_revision:25728; number_of_response:1; }","duration":"116.58367ms","start":"2026-05-16T02:14:13.775257Z","end":"2026-05-16T02:14:13.891841Z","steps":["trace[546609009] 'process raft request' (duration: 110.984136ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:14.054722Z","caller":"traceutil/trace.go:171","msg":"trace[805261462] transaction","detail":"{read_only:false; response_revision:25729; number_of_response:1; }","duration":"257.183237ms","start":"2026-05-16T02:14:13.797499Z","end":"2026-05-16T02:14:14.054683Z","steps":["trace[805261462] 'process raft request' (duration: 240.388616ms)","trace[805261462] 'compare' (duration: 16.507905ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:14.055018Z","caller":"traceutil/trace.go:171","msg":"trace[1883063294] transaction","detail":"{read_only:false; response_revision:25730; number_of_response:1; }","duration":"253.39129ms","start":"2026-05-16T02:14:13.801605Z","end":"2026-05-16T02:14:14.054996Z","steps":["trace[1883063294] 'process raft request' (duration: 252.985042ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:14.055305Z","caller":"traceutil/trace.go:171","msg":"trace[1610315225] transaction","detail":"{read_only:false; response_revision:25731; number_of_response:1; }","duration":"158.317606ms","start":"2026-05-16T02:14:13.896977Z","end":"2026-05-16T02:14:14.055295Z","steps":["trace[1610315225] 'process raft request' (duration: 157.875966ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:14.055386Z","caller":"traceutil/trace.go:171","msg":"trace[2124433565] linearizableReadLoop","detail":"{readStateIndex:26584; appliedIndex:26581; }","duration":"105.388984ms","start":"2026-05-16T02:14:13.949916Z","end":"2026-05-16T02:14:14.055305Z","steps":["trace[2124433565] 'read index received' (duration: 87.992521ms)","trace[2124433565] 'applied index is now lower than readState.Index' (duration: 17.395143ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:14.05572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.809443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-16T02:14:14.0558Z","caller":"traceutil/trace.go:171","msg":"trace[1412473750] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25732; }","duration":"105.976705ms","start":"2026-05-16T02:14:13.949804Z","end":"2026-05-16T02:14:14.05578Z","steps":["trace[1412473750] 'agreement among raft nodes before linearized reading' (duration: 105.763101ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:14.232735Z","caller":"traceutil/trace.go:171","msg":"trace[1552308455] transaction","detail":"{read_only:false; response_revision:25734; number_of_response:1; }","duration":"170.528772ms","start":"2026-05-16T02:14:14.062167Z","end":"2026-05-16T02:14:14.232696Z","steps":["trace[1552308455] 'process raft request' (duration: 152.342004ms)","trace[1552308455] 'compare' (duration: 17.806691ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:14.581193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.713777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-16T02:14:14.581547Z","caller":"traceutil/trace.go:171","msg":"trace[933453763] linearizableReadLoop","detail":"{readStateIndex:26590; appliedIndex:26587; }","duration":"175.269318ms","start":"2026-05-16T02:14:14.406233Z","end":"2026-05-16T02:14:14.581502Z","steps":["trace[933453763] 'read index received' (duration: 130.601564ms)","trace[933453763] 'applied index is now lower than readState.Index' (duration: 44.666014ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:14.581684Z","caller":"traceutil/trace.go:171","msg":"trace[1365640515] transaction","detail":"{read_only:false; response_revision:25735; number_of_response:1; }","duration":"319.672082ms","start":"2026-05-16T02:14:14.261988Z","end":"2026-05-16T02:14:14.58166Z","steps":["trace[1365640515] 'process raft request' (duration: 143.365753ms)","trace[1365640515] 'compare' (duration: 175.477093ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:14.581757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:14.261968Z","time spent":"319.753105ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T02:14:14.58207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.80619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-16T02:14:14.582158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.666946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-16T02:14:14.582166Z","caller":"traceutil/trace.go:171","msg":"trace[258006161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25737; }","duration":"175.952643ms","start":"2026-05-16T02:14:14.406192Z","end":"2026-05-16T02:14:14.582145Z","steps":["trace[258006161] 'agreement among raft nodes before linearized reading' (duration: 175.653987ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:14.582199Z","caller":"traceutil/trace.go:171","msg":"trace[807410473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25737; }","duration":"172.725748ms","start":"2026-05-16T02:14:14.409459Z","end":"2026-05-16T02:14:14.582185Z","steps":["trace[807410473] 'agreement among raft nodes before linearized reading' (duration: 172.565535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:14.582355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.529853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:14.58239Z","caller":"traceutil/trace.go:171","msg":"trace[1519305798] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:25737; }","duration":"170.583664ms","start":"2026-05-16T02:14:14.411797Z","end":"2026-05-16T02:14:14.58238Z","steps":["trace[1519305798] 'agreement among raft nodes before linearized reading' (duration: 170.522203ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:14.582622Z","caller":"traceutil/trace.go:171","msg":"trace[1378974869] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"306.192179ms","start":"2026-05-16T02:14:14.276413Z","end":"2026-05-16T02:14:14.582605Z","steps":["trace[1378974869] 'process raft request' (duration: 304.925443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:14.582809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:14.276404Z","time spent":"306.287741ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:14.583076Z","caller":"traceutil/trace.go:171","msg":"trace[1966649630] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"183.643519ms","start":"2026-05-16T02:14:14.399416Z","end":"2026-05-16T02:14:14.58306Z","steps":["trace[1966649630] 'process raft request' (duration: 182.043426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:14.808421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.212231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-16T02:14:14.808577Z","caller":"traceutil/trace.go:171","msg":"trace[1421169116] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"218.441943ms","start":"2026-05-16T02:14:14.59011Z","end":"2026-05-16T02:14:14.808552Z","steps":["trace[1421169116] 'process raft request' (duration: 107.974757ms)","trace[1421169116] 'compare' (duration: 110.068659ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:14.885983Z","caller":"traceutil/trace.go:171","msg":"trace[1271765764] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"291.704547ms","start":"2026-05-16T02:14:14.594235Z","end":"2026-05-16T02:14:14.88594Z","steps":["trace[1271765764] 'process raft request' (duration: 291.3557ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:14.886705Z","caller":"traceutil/trace.go:171","msg":"trace[1412702280] linearizableReadLoop","detail":"{readStateIndex:26593; appliedIndex:26590; }","duration":"235.940357ms","start":"2026-05-16T02:14:14.65073Z","end":"2026-05-16T02:14:14.886671Z","steps":["trace[1412702280] 'read index received' (duration: 47.37242ms)","trace[1412702280] 'applied index is now lower than readState.Index' (duration: 188.565777ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:14.887049Z","caller":"traceutil/trace.go:171","msg":"trace[532871973] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"257.545933ms","start":"2026-05-16T02:14:14.629487Z","end":"2026-05-16T02:14:14.887033Z","steps":["trace[532871973] 'process raft request' (duration: 256.34992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:14.887414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.666382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-16T02:14:14.88748Z","caller":"traceutil/trace.go:171","msg":"trace[264289395] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25740; }","duration":"236.812156ms","start":"2026-05-16T02:14:14.650652Z","end":"2026-05-16T02:14:14.887464Z","steps":["trace[264289395] 'agreement among raft nodes before linearized reading' (duration: 236.579371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:14.887749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.638665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:14.887809Z","caller":"traceutil/trace.go:171","msg":"trace[729474772] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:25740; }","duration":"130.760049ms","start":"2026-05-16T02:14:14.757035Z","end":"2026-05-16T02:14:14.887795Z","steps":["trace[729474772] 'agreement among raft nodes before linearized reading' (duration: 130.662707ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:15.854321Z","caller":"traceutil/trace.go:171","msg":"trace[1291893188] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"131.065004ms","start":"2026-05-16T02:14:15.723221Z","end":"2026-05-16T02:14:15.854286Z","steps":["trace[1291893188] 'process raft request' (duration: 130.8423ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:16.258337Z","caller":"traceutil/trace.go:171","msg":"trace[348187197] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"195.879126ms","start":"2026-05-16T02:14:16.062423Z","end":"2026-05-16T02:14:16.258302Z","steps":["trace[348187197] 'process raft request' (duration: 195.662462ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:16.505973Z","caller":"traceutil/trace.go:171","msg":"trace[913986631] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"443.366997ms","start":"2026-05-16T02:14:16.062574Z","end":"2026-05-16T02:14:16.505941Z","steps":["trace[913986631] 'process raft request' (duration: 442.958259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:16.506184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:16.062545Z","time spent":"443.52382ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:16.506382Z","caller":"traceutil/trace.go:171","msg":"trace[1673490347] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"443.00014ms","start":"2026-05-16T02:14:16.063327Z","end":"2026-05-16T02:14:16.506327Z","steps":["trace[1673490347] 'process raft request' (duration: 442.436108ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:16.506445Z","caller":"traceutil/trace.go:171","msg":"trace[625513342] linearizableReadLoop","detail":"{readStateIndex:26600; appliedIndex:26597; }","duration":"433.601049ms","start":"2026-05-16T02:14:16.072805Z","end":"2026-05-16T02:14:16.506406Z","steps":["trace[625513342] 'read index received' (duration: 185.325682ms)","trace[625513342] 'applied index is now lower than readState.Index' (duration: 248.272357ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:16.506595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:16.063289Z","time spent":"443.190515ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:16.506649Z","caller":"traceutil/trace.go:171","msg":"trace[151234487] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"394.856994ms","start":"2026-05-16T02:14:16.111765Z","end":"2026-05-16T02:14:16.506622Z","steps":["trace[151234487] 'process raft request' (duration: 394.053627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:16.506685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.120641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:16.506736Z","caller":"traceutil/trace.go:171","msg":"trace[699381150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25747; }","duration":"361.220743ms","start":"2026-05-16T02:14:16.145503Z","end":"2026-05-16T02:14:16.506724Z","steps":["trace[699381150] 'agreement among raft nodes before linearized reading' (duration: 361.141082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:16.506768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:16.145479Z","time spent":"361.283155ms","remote":"127.0.0.1:42586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-16T02:14:16.506826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:16.111737Z","time spent":"394.981717ms","remote":"127.0.0.1:42760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7732,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T02:14:16.506872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.852683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:16.506964Z","caller":"traceutil/trace.go:171","msg":"trace[1216615388] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25747; }","duration":"106.973436ms","start":"2026-05-16T02:14:16.399969Z","end":"2026-05-16T02:14:16.506943Z","steps":["trace[1216615388] 'agreement among raft nodes before linearized reading' (duration: 106.814863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:16.507044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.2113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-16T02:14:16.507082Z","caller":"traceutil/trace.go:171","msg":"trace[586464371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25747; }","duration":"266.305231ms","start":"2026-05-16T02:14:16.240768Z","end":"2026-05-16T02:14:16.507073Z","steps":["trace[586464371] 'agreement among raft nodes before linearized reading' (duration: 266.158158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:16.507286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.424986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-16T02:14:16.507472Z","caller":"traceutil/trace.go:171","msg":"trace[336796201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25747; }","duration":"434.664411ms","start":"2026-05-16T02:14:16.072784Z","end":"2026-05-16T02:14:16.507448Z","steps":["trace[336796201] 'agreement among raft nodes before linearized reading' (duration: 433.950987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:16.508424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:16.07272Z","time spent":"435.65894ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-16T02:14:23.671379Z","caller":"traceutil/trace.go:171","msg":"trace[166537043] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"157.369646ms","start":"2026-05-16T02:14:23.513975Z","end":"2026-05-16T02:14:23.671344Z","steps":["trace[166537043] 'process raft request' (duration: 156.122612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:24.355452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.663264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:24.355578Z","caller":"traceutil/trace.go:171","msg":"trace[588727508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25810; }","duration":"207.853838ms","start":"2026-05-16T02:14:24.147699Z","end":"2026-05-16T02:14:24.355553Z","steps":["trace[588727508] 'range keys from in-memory index tree' (duration: 207.538061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:24.355822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.361782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:24.355875Z","caller":"traceutil/trace.go:171","msg":"trace[1577711790] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:25810; }","duration":"183.470156ms","start":"2026-05-16T02:14:24.172388Z","end":"2026-05-16T02:14:24.355858Z","steps":["trace[1577711790] 'count revisions from in-memory index tree' (duration: 183.23823ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:24.97606Z","caller":"traceutil/trace.go:171","msg":"trace[1314717626] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"440.674222ms","start":"2026-05-16T02:14:24.535357Z","end":"2026-05-16T02:14:24.976031Z","steps":["trace[1314717626] 'process raft request' (duration: 440.421217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:24.976266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:24.535332Z","time spent":"440.812444ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:25.185709Z","caller":"traceutil/trace.go:171","msg":"trace[845739587] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"627.464454ms","start":"2026-05-16T02:14:24.558191Z","end":"2026-05-16T02:14:25.185655Z","steps":["trace[845739587] 'process raft request' (duration: 626.442113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:25.18596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:24.558167Z","time spent":"627.643448ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:25.186141Z","caller":"traceutil/trace.go:171","msg":"trace[985551773] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"539.458033ms","start":"2026-05-16T02:14:24.64664Z","end":"2026-05-16T02:14:25.186098Z","steps":["trace[985551773] 'process raft request' (duration: 539.209328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:25.186398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:24.646614Z","time spent":"539.633486ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:25.18661Z","caller":"traceutil/trace.go:171","msg":"trace[547261991] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"539.276298ms","start":"2026-05-16T02:14:24.647318Z","end":"2026-05-16T02:14:25.186594Z","steps":["trace[547261991] 'process raft request' (duration: 538.936641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:25.1867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:24.647302Z","time spent":"539.357039ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T02:14:25.483975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.608585ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-16T02:14:25.484532Z","caller":"traceutil/trace.go:171","msg":"trace[331966985] linearizableReadLoop","detail":"{readStateIndex:26668; appliedIndex:26666; }","duration":"870.663047ms","start":"2026-05-16T02:14:24.613829Z","end":"2026-05-16T02:14:25.484492Z","steps":["trace[331966985] 'read index received' (duration: 362.006409ms)","trace[331966985] 'applied index is now lower than readState.Index' (duration: 508.654208ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:25.484592Z","caller":"traceutil/trace.go:171","msg":"trace[34413025] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"546.178688ms","start":"2026-05-16T02:14:24.938363Z","end":"2026-05-16T02:14:25.484541Z","steps":["trace[34413025] 'process raft request' (duration: 545.77932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:25.484828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:24.938328Z","time spent":"546.345363ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T02:14:25.798786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.903899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-16T02:14:25.798884Z","caller":"traceutil/trace.go:171","msg":"trace[1187298833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25816; }","duration":"854.067742ms","start":"2026-05-16T02:14:24.944792Z","end":"2026-05-16T02:14:25.79886Z","steps":["trace[1187298833] 'agreement among raft nodes before linearized reading' (duration: 539.952042ms)","trace[1187298833] 'range keys from in-memory index tree' (duration: 313.847165ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:25.798923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:24.944779Z","time spent":"854.135774ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-16T02:14:25.798883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185018073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-16T02:14:25.799001Z","caller":"traceutil/trace.go:171","msg":"trace[1202639833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25816; }","duration":"1.185207507s","start":"2026-05-16T02:14:24.613759Z","end":"2026-05-16T02:14:25.798967Z","steps":["trace[1202639833] 'agreement among raft nodes before linearized reading' (duration: 870.860552ms)","trace[1202639833] 'range keys from in-memory index tree' (duration: 314.05407ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:25.799012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.153166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-16T02:14:25.799116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154451234s","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-05-16T02:14:25.799181Z","caller":"traceutil/trace.go:171","msg":"trace[1698146979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25816; }","duration":"1.154558906s","start":"2026-05-16T02:14:24.644602Z","end":"2026-05-16T02:14:25.799161Z","steps":["trace[1698146979] 'agreement among raft nodes before linearized reading' (duration: 840.10696ms)","trace[1698146979] 'range keys from in-memory index tree' (duration: 314.307634ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:25.799218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:24.64459Z","time spent":"1.154620597s","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-16T02:14:25.799191Z","caller":"traceutil/trace.go:171","msg":"trace[1956482309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25816; }","duration":"940.415701ms","start":"2026-05-16T02:14:24.858739Z","end":"2026-05-16T02:14:25.799155Z","steps":["trace[1956482309] 'agreement among raft nodes before linearized reading' (duration: 626.023846ms)","trace[1956482309] 'range keys from in-memory index tree' (duration: 314.019068ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:25.799269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.621782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-7fd2k.18afe953c97c7adb\" ","response":"range_response_count:1 size:1395"} {"level":"info","ts":"2026-05-16T02:14:25.799299Z","caller":"traceutil/trace.go:171","msg":"trace[383938532] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-7fd2k.18afe953c97c7adb; range_end:; response_count:1; response_revision:25816; }","duration":"886.646583ms","start":"2026-05-16T02:14:24.91264Z","end":"2026-05-16T02:14:25.799287Z","steps":["trace[383938532] 'agreement among raft nodes before linearized reading' (duration: 572.114015ms)","trace[383938532] 'range keys from in-memory index tree' (duration: 314.436606ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:25.799321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:24.912628Z","time spent":"886.687483ms","remote":"127.0.0.1:42662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":1418,"request content":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-7fd2k.18afe953c97c7adb\" "} {"level":"warn","ts":"2026-05-16T02:14:25.799287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:24.858708Z","time spent":"940.563595ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-16T02:14:25.799077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:24.613726Z","time spent":"1.185334599s","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-16T02:14:25.799796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.128716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1115"} {"level":"info","ts":"2026-05-16T02:14:25.800335Z","caller":"traceutil/trace.go:171","msg":"trace[1344080872] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:25816; }","duration":"257.736078ms","start":"2026-05-16T02:14:25.542573Z","end":"2026-05-16T02:14:25.800309Z","steps":["trace[1344080872] 'range keys from in-memory index tree' (duration: 256.80728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:25.800344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14881985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-16T02:14:25.800458Z","caller":"traceutil/trace.go:171","msg":"trace[319405326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25816; }","duration":"1.148970464s","start":"2026-05-16T02:14:24.651454Z","end":"2026-05-16T02:14:25.800425Z","steps":["trace[319405326] 'agreement among raft nodes before linearized reading' (duration: 833.322793ms)","trace[319405326] 'range keys from in-memory index tree' (duration: 314.047909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:25.800515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:24.651444Z","time spent":"1.149060635s","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-16T02:14:26.100762Z","caller":"traceutil/trace.go:171","msg":"trace[1459932769] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"422.565336ms","start":"2026-05-16T02:14:25.678162Z","end":"2026-05-16T02:14:26.100728Z","steps":["trace[1459932769] 'process raft request' (duration: 422.344481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:26.101136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:25.678125Z","time spent":"422.901213ms","remote":"127.0.0.1:60944","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-05-16T02:14:26.104302Z","caller":"traceutil/trace.go:171","msg":"trace[1455943503] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"302.479894ms","start":"2026-05-16T02:14:25.80179Z","end":"2026-05-16T02:14:26.10427Z","steps":["trace[1455943503] 'process raft request' (duration: 302.197798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:26.104635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:25.80176Z","time spent":"302.74705ms","remote":"127.0.0.1:42662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1377,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:26.104684Z","caller":"traceutil/trace.go:171","msg":"trace[186927804] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"299.759129ms","start":"2026-05-16T02:14:25.804895Z","end":"2026-05-16T02:14:26.104654Z","steps":["trace[186927804] 'process raft request' (duration: 299.291659ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:26.105015Z","caller":"traceutil/trace.go:171","msg":"trace[378468490] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"299.877682ms","start":"2026-05-16T02:14:25.805125Z","end":"2026-05-16T02:14:26.105003Z","steps":["trace[378468490] 'process raft request' (duration: 299.468474ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:26.105912Z","caller":"traceutil/trace.go:171","msg":"trace[707670866] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"300.439222ms","start":"2026-05-16T02:14:25.805456Z","end":"2026-05-16T02:14:26.105895Z","steps":["trace[707670866] 'process raft request' (duration: 299.968983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:26.106009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:25.805441Z","time spent":"300.525105ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:26.106873Z","caller":"traceutil/trace.go:171","msg":"trace[1915088471] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"300.939843ms","start":"2026-05-16T02:14:25.805904Z","end":"2026-05-16T02:14:26.106844Z","steps":["trace[1915088471] 'process raft request' (duration: 299.739638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:26.107108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:25.805891Z","time spent":"301.034476ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:26.10765Z","caller":"traceutil/trace.go:171","msg":"trace[212296985] transaction","detail":"{read_only:false; response_revision:25823; number_of_response:1; }","duration":"301.445674ms","start":"2026-05-16T02:14:25.806179Z","end":"2026-05-16T02:14:26.107625Z","steps":["trace[212296985] 'process raft request' (duration: 300.526766ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:26.107617Z","caller":"traceutil/trace.go:171","msg":"trace[1705565532] linearizableReadLoop","detail":"{readStateIndex:26674; appliedIndex:26672; }","duration":"305.500376ms","start":"2026-05-16T02:14:25.802081Z","end":"2026-05-16T02:14:26.107581Z","steps":["trace[1705565532] 'read index received' (duration: 298.496574ms)","trace[1705565532] 'applied index is now lower than readState.Index' (duration: 7.002042ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:26.107694Z","caller":"traceutil/trace.go:171","msg":"trace[1436499473] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"133.593735ms","start":"2026-05-16T02:14:25.974061Z","end":"2026-05-16T02:14:26.107654Z","steps":["trace[1436499473] 'process raft request' (duration: 133.436162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:26.107755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:25.806151Z","time spent":"301.549225ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T02:14:26.107896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.810102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-zpfpq\" ","response":"range_response_count:1 size:5934"} {"level":"warn","ts":"2026-05-16T02:14:26.107917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.9814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:26.107941Z","caller":"traceutil/trace.go:171","msg":"trace[334111208] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-zpfpq; range_end:; response_count:1; response_revision:25824; }","duration":"305.876963ms","start":"2026-05-16T02:14:25.802052Z","end":"2026-05-16T02:14:26.107929Z","steps":["trace[334111208] 'agreement among raft nodes before linearized reading' (duration: 305.71512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:26.107902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.026507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:26.107968Z","caller":"traceutil/trace.go:171","msg":"trace[45354526] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:25824; }","duration":"120.121662ms","start":"2026-05-16T02:14:25.987829Z","end":"2026-05-16T02:14:26.10795Z","steps":["trace[45354526] 'agreement among raft nodes before linearized reading' (duration: 119.99544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:26.107975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:25.802041Z","time spent":"305.929274ms","remote":"127.0.0.1:42758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":5957,"request content":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-zpfpq\" "} {"level":"info","ts":"2026-05-16T02:14:26.108015Z","caller":"traceutil/trace.go:171","msg":"trace[933413044] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25824; }","duration":"223.20198ms","start":"2026-05-16T02:14:25.884784Z","end":"2026-05-16T02:14:26.107986Z","steps":["trace[933413044] 'agreement among raft nodes before linearized reading' (duration: 223.027857ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:33.640941Z","caller":"traceutil/trace.go:171","msg":"trace[2110382281] transaction","detail":"{read_only:false; response_revision:25946; number_of_response:1; }","duration":"125.848508ms","start":"2026-05-16T02:14:33.515058Z","end":"2026-05-16T02:14:33.640906Z","steps":["trace[2110382281] 'process raft request' (duration: 125.616134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:33.912778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.686892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:33.912896Z","caller":"traceutil/trace.go:171","msg":"trace[104357185] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25946; }","duration":"114.864416ms","start":"2026-05-16T02:14:33.798001Z","end":"2026-05-16T02:14:33.912866Z","steps":["trace[104357185] 'count revisions from in-memory index tree' (duration: 114.60096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:33.913226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.907947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:33.913337Z","caller":"traceutil/trace.go:171","msg":"trace[1293480943] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25946; }","duration":"198.05096ms","start":"2026-05-16T02:14:33.715262Z","end":"2026-05-16T02:14:33.913313Z","steps":["trace[1293480943] 'count revisions from in-memory index tree' (duration: 197.825525ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:34.369935Z","caller":"traceutil/trace.go:171","msg":"trace[1576520569] linearizableReadLoop","detail":"{readStateIndex:26805; appliedIndex:26803; }","duration":"234.09595ms","start":"2026-05-16T02:14:34.135812Z","end":"2026-05-16T02:14:34.369908Z","steps":["trace[1576520569] 'read index received' (duration: 52.180186ms)","trace[1576520569] 'applied index is now lower than readState.Index' (duration: 181.914814ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:34.370147Z","caller":"traceutil/trace.go:171","msg":"trace[1044671618] transaction","detail":"{read_only:false; response_revision:25948; number_of_response:1; }","duration":"238.126202ms","start":"2026-05-16T02:14:34.13201Z","end":"2026-05-16T02:14:34.370137Z","steps":["trace[1044671618] 'process raft request' (duration: 184.714151ms)","trace[1044671618] 'compare' (duration: 52.799849ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:34.37035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.522948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:34.370381Z","caller":"traceutil/trace.go:171","msg":"trace[1356314673] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:25948; }","duration":"234.574309ms","start":"2026-05-16T02:14:34.135799Z","end":"2026-05-16T02:14:34.370374Z","steps":["trace[1356314673] 'agreement among raft nodes before linearized reading' (duration: 234.500987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:34.370533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.786702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-16T02:14:34.370553Z","caller":"traceutil/trace.go:171","msg":"trace[388292679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25948; }","duration":"228.834702ms","start":"2026-05-16T02:14:34.141714Z","end":"2026-05-16T02:14:34.370549Z","steps":["trace[388292679] 'agreement among raft nodes before linearized reading' (duration: 228.760481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:34.370657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.201677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-697cd455-29f9-4836-8efc-ee8aaf2ecb92\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-05-16T02:14:34.370634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.047536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:34.370707Z","caller":"traceutil/trace.go:171","msg":"trace[1463439734] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25948; }","duration":"104.152249ms","start":"2026-05-16T02:14:34.266539Z","end":"2026-05-16T02:14:34.370691Z","steps":["trace[1463439734] 'agreement among raft nodes before linearized reading' (duration: 103.969425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:34.370752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.837361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-16T02:14:34.370767Z","caller":"traceutil/trace.go:171","msg":"trace[166166581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25948; }","duration":"219.908483ms","start":"2026-05-16T02:14:34.150855Z","end":"2026-05-16T02:14:34.370764Z","steps":["trace[166166581] 'agreement among raft nodes before linearized reading' (duration: 219.871222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:34.370837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.068748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-16T02:14:34.370848Z","caller":"traceutil/trace.go:171","msg":"trace[65220154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25948; }","duration":"225.096508ms","start":"2026-05-16T02:14:34.145748Z","end":"2026-05-16T02:14:34.370844Z","steps":["trace[65220154] 'agreement among raft nodes before linearized reading' (duration: 225.060598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:34.370922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.506537ms","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-05-16T02:14:34.370933Z","caller":"traceutil/trace.go:171","msg":"trace[751803403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25948; }","duration":"225.538997ms","start":"2026-05-16T02:14:34.14539Z","end":"2026-05-16T02:14:34.370929Z","steps":["trace[751803403] 'agreement among raft nodes before linearized reading' (duration: 225.499786ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:34.370674Z","caller":"traceutil/trace.go:171","msg":"trace[580290920] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-697cd455-29f9-4836-8efc-ee8aaf2ecb92; range_end:; response_count:1; response_revision:25948; }","duration":"211.232378ms","start":"2026-05-16T02:14:34.159434Z","end":"2026-05-16T02:14:34.370666Z","steps":["trace[580290920] 'agreement among raft nodes before linearized reading' (duration: 211.185477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:34.370714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.420113ms","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-05-16T02:14:34.371175Z","caller":"traceutil/trace.go:171","msg":"trace[182676258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25948; }","duration":"218.882842ms","start":"2026-05-16T02:14:34.152282Z","end":"2026-05-16T02:14:34.371164Z","steps":["trace[182676258] 'agreement among raft nodes before linearized reading' (duration: 218.402452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:34.698646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.014468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-16T02:14:34.698791Z","caller":"traceutil/trace.go:171","msg":"trace[424039459] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"323.602713ms","start":"2026-05-16T02:14:34.37516Z","end":"2026-05-16T02:14:34.698762Z","steps":["trace[424039459] 'process raft request' (duration: 130.34089ms)","trace[424039459] 'compare' (duration: 192.447335ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:34.698874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:34.375143Z","time spent":"323.689004ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:34.866307Z","caller":"traceutil/trace.go:171","msg":"trace[910306056] linearizableReadLoop","detail":"{readStateIndex:26812; appliedIndex:26805; }","duration":"449.508441ms","start":"2026-05-16T02:14:34.416758Z","end":"2026-05-16T02:14:34.866266Z","steps":["trace[910306056] 'read index received' (duration: 88.738327ms)","trace[910306056] 'applied index is now lower than readState.Index' (duration: 360.768384ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:34.866694Z","caller":"traceutil/trace.go:171","msg":"trace[175890053] transaction","detail":"{read_only:false; response_revision:25950; number_of_response:1; }","duration":"489.854318ms","start":"2026-05-16T02:14:34.37682Z","end":"2026-05-16T02:14:34.866674Z","steps":["trace[175890053] 'process raft request' (duration: 428.965055ms)","trace[175890053] 'compare' (duration: 60.050566ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:34.866749Z","caller":"traceutil/trace.go:171","msg":"trace[173011664] transaction","detail":"{read_only:false; response_revision:25954; number_of_response:1; }","duration":"485.632434ms","start":"2026-05-16T02:14:34.381067Z","end":"2026-05-16T02:14:34.8667Z","steps":["trace[173011664] 'process raft request' (duration: 485.114113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:34.866835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:34.376776Z","time spent":"489.959431ms","remote":"127.0.0.1:60944","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-05-16T02:14:34.866949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:34.381035Z","time spent":"485.809397ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:34.867197Z","caller":"traceutil/trace.go:171","msg":"trace[86872478] transaction","detail":"{read_only:false; response_revision:25952; number_of_response:1; }","duration":"486.257766ms","start":"2026-05-16T02:14:34.380927Z","end":"2026-05-16T02:14:34.867185Z","steps":["trace[86872478] 'process raft request' (duration: 485.134652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:34.867272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:34.380918Z","time spent":"486.318168ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:34.867359Z","caller":"traceutil/trace.go:171","msg":"trace[442960124] transaction","detail":"{read_only:false; response_revision:25953; number_of_response:1; }","duration":"486.358588ms","start":"2026-05-16T02:14:34.380987Z","end":"2026-05-16T02:14:34.867345Z","steps":["trace[442960124] 'process raft request' (duration: 485.137452ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:34.867314Z","caller":"traceutil/trace.go:171","msg":"trace[1417759383] transaction","detail":"{read_only:false; response_revision:25951; number_of_response:1; }","duration":"490.262026ms","start":"2026-05-16T02:14:34.376992Z","end":"2026-05-16T02:14:34.867254Z","steps":["trace[1417759383] 'process raft request' (duration: 489.01667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:34.867431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:34.380967Z","time spent":"486.42312ms","remote":"127.0.0.1:42758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:34.867506Z","caller":"traceutil/trace.go:171","msg":"trace[2119137467] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"475.255193ms","start":"2026-05-16T02:14:34.392229Z","end":"2026-05-16T02:14:34.867484Z","steps":["trace[2119137467] 'process raft request' (duration: 473.986116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:34.867547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:34.376982Z","time spent":"490.4465ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T02:14:34.867592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:34.392222Z","time spent":"475.325165ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T02:14:34.867782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.022992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-16T02:14:34.867841Z","caller":"traceutil/trace.go:171","msg":"trace[1690462265] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25955; }","duration":"451.088233ms","start":"2026-05-16T02:14:34.416736Z","end":"2026-05-16T02:14:34.867824Z","steps":["trace[1690462265] 'agreement among raft nodes before linearized reading' (duration: 450.91835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:34.867879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:34.416729Z","time spent":"451.143714ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3332,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-16T02:14:34.867902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.588107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-697cd455-29f9-4836-8efc-ee8aaf2ecb92\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-16T02:14:34.868415Z","caller":"traceutil/trace.go:171","msg":"trace[1941858138] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-697cd455-29f9-4836-8efc-ee8aaf2ecb92; range_end:; response_count:1; response_revision:25955; }","duration":"426.028726ms","start":"2026-05-16T02:14:34.442353Z","end":"2026-05-16T02:14:34.868382Z","steps":["trace[1941858138] 'agreement among raft nodes before linearized reading' (duration: 424.440275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:34.868862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:34.442323Z","time spent":"426.513355ms","remote":"127.0.0.1:42758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-697cd455-29f9-4836-8efc-ee8aaf2ecb92\" "} {"level":"info","ts":"2026-05-16T02:14:35.043301Z","caller":"traceutil/trace.go:171","msg":"trace[1601656183] linearizableReadLoop","detail":"{readStateIndex:26813; appliedIndex:26812; }","duration":"159.798635ms","start":"2026-05-16T02:14:34.883479Z","end":"2026-05-16T02:14:35.043278Z","steps":["trace[1601656183] 'read index received' (duration: 97.889702ms)","trace[1601656183] 'applied index is now lower than readState.Index' (duration: 61.907963ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:35.043542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.01939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-05-16T02:14:35.043581Z","caller":"traceutil/trace.go:171","msg":"trace[1403006610] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25956; }","duration":"160.103732ms","start":"2026-05-16T02:14:34.883465Z","end":"2026-05-16T02:14:35.043569Z","steps":["trace[1403006610] 'agreement among raft nodes before linearized reading' (duration: 159.904428ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:35.043928Z","caller":"traceutil/trace.go:171","msg":"trace[1336641136] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"160.937059ms","start":"2026-05-16T02:14:34.882977Z","end":"2026-05-16T02:14:35.043914Z","steps":["trace[1336641136] 'process raft request' (duration: 98.451884ms)","trace[1336641136] 'compare' (duration: 61.486054ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:35.171184Z","caller":"traceutil/trace.go:171","msg":"trace[719518257] linearizableReadLoop","detail":"{readStateIndex:26815; appliedIndex:26813; }","duration":"123.43459ms","start":"2026-05-16T02:14:35.047721Z","end":"2026-05-16T02:14:35.171155Z","steps":["trace[719518257] 'read index received' (duration: 72.997839ms)","trace[719518257] 'applied index is now lower than readState.Index' (duration: 50.435151ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:35.171322Z","caller":"traceutil/trace.go:171","msg":"trace[1169952901] transaction","detail":"{read_only:false; response_revision:25957; number_of_response:1; }","duration":"162.641533ms","start":"2026-05-16T02:14:35.008634Z","end":"2026-05-16T02:14:35.171276Z","steps":["trace[1169952901] 'process raft request' (duration: 162.332807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:35.171445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.707345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18afea02124b1da8\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-05-16T02:14:35.171492Z","caller":"traceutil/trace.go:171","msg":"trace[2024626015] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18afea02124b1da8; range_end:; response_count:1; response_revision:25957; }","duration":"123.820947ms","start":"2026-05-16T02:14:35.047658Z","end":"2026-05-16T02:14:35.171479Z","steps":["trace[2024626015] 'agreement among raft nodes before linearized reading' (duration: 123.639073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:35.17153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.802965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-05-16T02:14:35.171613Z","caller":"traceutil/trace.go:171","msg":"trace[165699009] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25957; }","duration":"122.930658ms","start":"2026-05-16T02:14:35.048653Z","end":"2026-05-16T02:14:35.171584Z","steps":["trace[165699009] 'agreement among raft nodes before linearized reading' (duration: 122.706254ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:42.475419Z","caller":"traceutil/trace.go:171","msg":"trace[456525624] linearizableReadLoop","detail":"{readStateIndex:26899; appliedIndex:26898; }","duration":"123.670914ms","start":"2026-05-16T02:14:42.351689Z","end":"2026-05-16T02:14:42.47536Z","steps":["trace[456525624] 'read index received' (duration: 56.425392ms)","trace[456525624] 'applied index is now lower than readState.Index' (duration: 67.243712ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:42.475638Z","caller":"traceutil/trace.go:171","msg":"trace[539220763] transaction","detail":"{read_only:false; response_revision:26039; number_of_response:1; }","duration":"175.521304ms","start":"2026-05-16T02:14:42.300099Z","end":"2026-05-16T02:14:42.47562Z","steps":["trace[539220763] 'process raft request' (duration: 108.055538ms)","trace[539220763] 'compare' (duration: 66.946175ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:42.47601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.294027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-16T02:14:42.476137Z","caller":"traceutil/trace.go:171","msg":"trace[759846624] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26040; }","duration":"124.47559ms","start":"2026-05-16T02:14:42.351624Z","end":"2026-05-16T02:14:42.4761Z","steps":["trace[759846624] 'agreement among raft nodes before linearized reading' (duration: 124.138314ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:56.018481Z","caller":"traceutil/trace.go:171","msg":"trace[1154681882] linearizableReadLoop","detail":"{readStateIndex:27013; appliedIndex:27012; }","duration":"101.487515ms","start":"2026-05-16T02:14:55.916957Z","end":"2026-05-16T02:14:56.018444Z","steps":["trace[1154681882] 'read index received' (duration: 101.138498ms)","trace[1154681882] 'applied index is now lower than readState.Index' (duration: 347.757µs)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:56.018552Z","caller":"traceutil/trace.go:171","msg":"trace[515313200] transaction","detail":"{read_only:false; response_revision:26150; number_of_response:1; }","duration":"266.100108ms","start":"2026-05-16T02:14:55.752408Z","end":"2026-05-16T02:14:56.018508Z","steps":["trace[515313200] 'process raft request' (duration: 265.782582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:56.018701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.719659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18417"} {"level":"info","ts":"2026-05-16T02:14:56.018772Z","caller":"traceutil/trace.go:171","msg":"trace[1998101415] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26150; }","duration":"101.855122ms","start":"2026-05-16T02:14:55.916894Z","end":"2026-05-16T02:14:56.018749Z","steps":["trace[1998101415] 'agreement among raft nodes before linearized reading' (duration: 101.697249ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:56.47297Z","caller":"traceutil/trace.go:171","msg":"trace[1774581721] linearizableReadLoop","detail":"{readStateIndex:27014; appliedIndex:27013; }","duration":"101.545535ms","start":"2026-05-16T02:14:56.37139Z","end":"2026-05-16T02:14:56.472936Z","steps":["trace[1774581721] 'read index received' (duration: 101.300791ms)","trace[1774581721] 'applied index is now lower than readState.Index' (duration: 243.634µs)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:56.473157Z","caller":"traceutil/trace.go:171","msg":"trace[1494516092] transaction","detail":"{read_only:false; response_revision:26151; number_of_response:1; }","duration":"128.730297ms","start":"2026-05-16T02:14:56.344367Z","end":"2026-05-16T02:14:56.473097Z","steps":["trace[1494516092] 'process raft request' (duration: 128.345779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:56.473268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.864682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-16T02:14:56.473319Z","caller":"traceutil/trace.go:171","msg":"trace[26002941] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26151; }","duration":"101.984305ms","start":"2026-05-16T02:14:56.37132Z","end":"2026-05-16T02:14:56.473305Z","steps":["trace[26002941] 'agreement among raft nodes before linearized reading' (duration: 101.74165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:56.786528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.463875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-16T02:14:56.78673Z","caller":"traceutil/trace.go:171","msg":"trace[1052968259] transaction","detail":"{read_only:false; response_revision:26154; number_of_response:1; }","duration":"225.935764ms","start":"2026-05-16T02:14:56.560776Z","end":"2026-05-16T02:14:56.786712Z","steps":["trace[1052968259] 'process raft request' (duration: 225.901783ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:56.786715Z","caller":"traceutil/trace.go:171","msg":"trace[1712500741] transaction","detail":"{read_only:false; response_revision:26152; number_of_response:1; }","duration":"308.570138ms","start":"2026-05-16T02:14:56.478106Z","end":"2026-05-16T02:14:56.786676Z","steps":["trace[1712500741] 'process raft request' (duration: 121.846848ms)","trace[1712500741] 'compare' (duration: 186.300772ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:56.786908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:56.47808Z","time spent":"308.72155ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:56.7871Z","caller":"traceutil/trace.go:171","msg":"trace[184037739] transaction","detail":"{read_only:false; response_revision:26153; number_of_response:1; }","duration":"260.949124ms","start":"2026-05-16T02:14:56.526107Z","end":"2026-05-16T02:14:56.787056Z","steps":["trace[184037739] 'process raft request' (duration: 260.524735ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:56.787097Z","caller":"traceutil/trace.go:171","msg":"trace[2116484116] linearizableReadLoop","detail":"{readStateIndex:27015; appliedIndex:27014; }","duration":"292.44107ms","start":"2026-05-16T02:14:56.494608Z","end":"2026-05-16T02:14:56.787049Z","steps":["trace[2116484116] 'read index received' (duration: 105.388964ms)","trace[2116484116] 'applied index is now lower than readState.Index' (duration: 187.044986ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:56.787345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.714287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-05-16T02:14:56.787403Z","caller":"traceutil/trace.go:171","msg":"trace[1810865708] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:26154; }","duration":"292.791899ms","start":"2026-05-16T02:14:56.494595Z","end":"2026-05-16T02:14:56.787387Z","steps":["trace[1810865708] 'agreement among raft nodes before linearized reading' (duration: 292.563345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:57.200707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.940012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-16T02:14:57.200896Z","caller":"traceutil/trace.go:171","msg":"trace[1880815206] transaction","detail":"{read_only:false; response_revision:26155; number_of_response:1; }","duration":"330.226036ms","start":"2026-05-16T02:14:56.87063Z","end":"2026-05-16T02:14:57.200856Z","steps":["trace[1880815206] 'process raft request' (duration: 67.959837ms)","trace[1880815206] 'compare' (duration: 261.548615ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:14:57.201014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:56.870612Z","time spent":"330.348449ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:14:57.28534Z","caller":"traceutil/trace.go:171","msg":"trace[405734980] linearizableReadLoop","detail":"{readStateIndex:27019; appliedIndex:27018; }","duration":"289.318338ms","start":"2026-05-16T02:14:56.995991Z","end":"2026-05-16T02:14:57.285309Z","steps":["trace[405734980] 'read index received' (duration: 289.084473ms)","trace[405734980] 'applied index is now lower than readState.Index' (duration: 232.305µs)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:14:57.285388Z","caller":"traceutil/trace.go:171","msg":"trace[140038621] transaction","detail":"{read_only:false; response_revision:26156; number_of_response:1; }","duration":"331.999312ms","start":"2026-05-16T02:14:56.953364Z","end":"2026-05-16T02:14:57.285363Z","steps":["trace[140038621] 'process raft request' (duration: 331.749527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:57.285556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:14:56.953339Z","time spent":"332.120074ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T02:14:57.285662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.656404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-16T02:14:57.28575Z","caller":"traceutil/trace.go:171","msg":"trace[1426950565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26156; }","duration":"289.806317ms","start":"2026-05-16T02:14:56.995924Z","end":"2026-05-16T02:14:57.28573Z","steps":["trace[1426950565] 'agreement among raft nodes before linearized reading' (duration: 289.526852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:57.289932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.074132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-16T02:14:57.289922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.840689ms","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-05-16T02:14:57.289957Z","caller":"traceutil/trace.go:171","msg":"trace[1999867907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26157; }","duration":"286.121173ms","start":"2026-05-16T02:14:57.00383Z","end":"2026-05-16T02:14:57.289952Z","steps":["trace[1999867907] 'agreement among raft nodes before linearized reading' (duration: 286.050972ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:14:57.289979Z","caller":"traceutil/trace.go:171","msg":"trace[1584404890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26157; }","duration":"292.914232ms","start":"2026-05-16T02:14:56.99705Z","end":"2026-05-16T02:14:57.289964Z","steps":["trace[1584404890] 'agreement among raft nodes before linearized reading' (duration: 292.707527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:57.290098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.461844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:14:57.290123Z","caller":"traceutil/trace.go:171","msg":"trace[324802483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26157; }","duration":"143.529886ms","start":"2026-05-16T02:14:57.146585Z","end":"2026-05-16T02:14:57.290115Z","steps":["trace[324802483] 'agreement among raft nodes before linearized reading' (duration: 143.483295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:14:57.290183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.177065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-16T02:14:57.290261Z","caller":"traceutil/trace.go:171","msg":"trace[1059658692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26157; }","duration":"288.279707ms","start":"2026-05-16T02:14:57.001957Z","end":"2026-05-16T02:14:57.290237Z","steps":["trace[1059658692] 'agreement among raft nodes before linearized reading' (duration: 287.963641ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:00.302702Z","caller":"traceutil/trace.go:171","msg":"trace[52378294] linearizableReadLoop","detail":"{readStateIndex:27058; appliedIndex:27057; }","duration":"160.373306ms","start":"2026-05-16T02:15:00.142298Z","end":"2026-05-16T02:15:00.302672Z","steps":["trace[52378294] 'read index received' (duration: 160.235244ms)","trace[52378294] 'applied index is now lower than readState.Index' (duration: 136.922µs)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:15:00.302782Z","caller":"traceutil/trace.go:171","msg":"trace[913507294] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"191.482216ms","start":"2026-05-16T02:15:00.111248Z","end":"2026-05-16T02:15:00.30273Z","steps":["trace[913507294] 'process raft request' (duration: 191.289403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:00.302911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.578122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-16T02:15:00.302958Z","caller":"traceutil/trace.go:171","msg":"trace[1121730114] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26193; }","duration":"160.693265ms","start":"2026-05-16T02:15:00.142246Z","end":"2026-05-16T02:15:00.30294Z","steps":["trace[1121730114] 'agreement among raft nodes before linearized reading' (duration: 160.577011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:00.302969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.249884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:15:00.303063Z","caller":"traceutil/trace.go:171","msg":"trace[1418936843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26193; }","duration":"159.373337ms","start":"2026-05-16T02:15:00.143668Z","end":"2026-05-16T02:15:00.303041Z","steps":["trace[1418936843] 'agreement among raft nodes before linearized reading' (duration: 159.203523ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:00.451917Z","caller":"traceutil/trace.go:171","msg":"trace[1638378127] linearizableReadLoop","detail":"{readStateIndex:27059; appliedIndex:27058; }","duration":"141.435984ms","start":"2026-05-16T02:15:00.31045Z","end":"2026-05-16T02:15:00.451886Z","steps":["trace[1638378127] 'read index received' (duration: 138.24498ms)","trace[1638378127] 'applied index is now lower than readState.Index' (duration: 3.189804ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:15:00.452182Z","caller":"traceutil/trace.go:171","msg":"trace[832555813] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"141.789001ms","start":"2026-05-16T02:15:00.310379Z","end":"2026-05-16T02:15:00.452168Z","steps":["trace[832555813] 'process raft request' (duration: 138.240329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:00.452235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.755251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:15:00.452386Z","caller":"traceutil/trace.go:171","msg":"trace[1254344124] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26194; }","duration":"141.953745ms","start":"2026-05-16T02:15:00.310414Z","end":"2026-05-16T02:15:00.452368Z","steps":["trace[1254344124] 'agreement among raft nodes before linearized reading' (duration: 141.71182ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:00.491697Z","caller":"traceutil/trace.go:171","msg":"trace[599101539] transaction","detail":"{read_only:false; response_revision:26195; number_of_response:1; }","duration":"181.063707ms","start":"2026-05-16T02:15:00.310613Z","end":"2026-05-16T02:15:00.491677Z","steps":["trace[599101539] 'process raft request' (duration: 180.882153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:00.491748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.22516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:15:00.491812Z","caller":"traceutil/trace.go:171","msg":"trace[714593135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26196; }","duration":"181.341472ms","start":"2026-05-16T02:15:00.310459Z","end":"2026-05-16T02:15:00.4918Z","steps":["trace[714593135] 'agreement among raft nodes before linearized reading' (duration: 181.22722ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:00.491714Z","caller":"traceutil/trace.go:171","msg":"trace[39604188] transaction","detail":"{read_only:false; response_revision:26196; number_of_response:1; }","duration":"180.933734ms","start":"2026-05-16T02:15:00.310764Z","end":"2026-05-16T02:15:00.491697Z","steps":["trace[39604188] 'process raft request' (duration: 180.851662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:00.740283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.183894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-16T02:15:00.740415Z","caller":"traceutil/trace.go:171","msg":"trace[2087115599] linearizableReadLoop","detail":"{readStateIndex:27062; appliedIndex:27061; }","duration":"248.728497ms","start":"2026-05-16T02:15:00.491666Z","end":"2026-05-16T02:15:00.740395Z","steps":["trace[2087115599] 'read index received' (duration: 125.183306ms)","trace[2087115599] 'applied index is now lower than readState.Index' (duration: 123.543491ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:15:00.74053Z","caller":"traceutil/trace.go:171","msg":"trace[1713133127] transaction","detail":"{read_only:false; response_revision:26197; number_of_response:1; }","duration":"255.805049ms","start":"2026-05-16T02:15:00.484672Z","end":"2026-05-16T02:15:00.740477Z","steps":["trace[1713133127] 'process raft request' (duration: 132.291229ms)","trace[1713133127] 'compare' (duration: 122.97892ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:15:00.740556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.567988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:15:00.741187Z","caller":"traceutil/trace.go:171","msg":"trace[640021302] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:26197; }","duration":"272.215351ms","start":"2026-05-16T02:15:00.468914Z","end":"2026-05-16T02:15:00.741129Z","steps":["trace[640021302] 'agreement among raft nodes before linearized reading' (duration: 271.558648ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:00.750227Z","caller":"traceutil/trace.go:171","msg":"trace[804659912] transaction","detail":"{read_only:false; response_revision:26199; number_of_response:1; }","duration":"251.480412ms","start":"2026-05-16T02:15:00.498717Z","end":"2026-05-16T02:15:00.750198Z","steps":["trace[804659912] 'process raft request' (duration: 251.293438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:00.750338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.577684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/openstack/heat-engine-cleaner\" ","response":"range_response_count:1 size:5538"} {"level":"info","ts":"2026-05-16T02:15:00.750442Z","caller":"traceutil/trace.go:171","msg":"trace[933552517] range","detail":"{range_begin:/registry/cronjobs/openstack/heat-engine-cleaner; range_end:; response_count:1; response_revision:26200; }","duration":"252.725307ms","start":"2026-05-16T02:15:00.497696Z","end":"2026-05-16T02:15:00.750421Z","steps":["trace[933552517] 'agreement among raft nodes before linearized reading' (duration: 252.436261ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:00.750476Z","caller":"traceutil/trace.go:171","msg":"trace[417086853] transaction","detail":"{read_only:false; response_revision:26198; number_of_response:1; }","duration":"252.531663ms","start":"2026-05-16T02:15:00.497936Z","end":"2026-05-16T02:15:00.750467Z","steps":["trace[417086853] 'process raft request' (duration: 251.895651ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:00.750828Z","caller":"traceutil/trace.go:171","msg":"trace[1201449524] transaction","detail":"{read_only:false; response_revision:26200; number_of_response:1; }","duration":"251.604653ms","start":"2026-05-16T02:15:00.499212Z","end":"2026-05-16T02:15:00.750816Z","steps":["trace[1201449524] 'process raft request' (duration: 250.866208ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:00.962905Z","caller":"traceutil/trace.go:171","msg":"trace[1889962074] transaction","detail":"{read_only:false; response_revision:26202; number_of_response:1; }","duration":"162.884467ms","start":"2026-05-16T02:15:00.799964Z","end":"2026-05-16T02:15:00.962849Z","steps":["trace[1889962074] 'process raft request' (duration: 130.009232ms)","trace[1889962074] 'compare' (duration: 32.309294ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:15:01.107662Z","caller":"traceutil/trace.go:171","msg":"trace[2024141145] linearizableReadLoop","detail":"{readStateIndex:27068; appliedIndex:27066; }","duration":"266.375463ms","start":"2026-05-16T02:15:00.841257Z","end":"2026-05-16T02:15:01.107632Z","steps":["trace[2024141145] 'read index received' (duration: 88.672235ms)","trace[2024141145] 'applied index is now lower than readState.Index' (duration: 177.701958ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:15:01.107752Z","caller":"traceutil/trace.go:171","msg":"trace[1952236156] transaction","detail":"{read_only:false; response_revision:26203; number_of_response:1; }","duration":"266.750811ms","start":"2026-05-16T02:15:00.840967Z","end":"2026-05-16T02:15:01.107718Z","steps":["trace[1952236156] 'process raft request' (duration: 262.315941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:01.107829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.550536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-16T02:15:01.107867Z","caller":"traceutil/trace.go:171","msg":"trace[1883773220] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26203; }","duration":"266.651699ms","start":"2026-05-16T02:15:00.841203Z","end":"2026-05-16T02:15:01.107854Z","steps":["trace[1883773220] 'agreement among raft nodes before linearized reading' (duration: 266.555847ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:01.177263Z","caller":"traceutil/trace.go:171","msg":"trace[1967384211] transaction","detail":"{read_only:false; response_revision:26204; number_of_response:1; }","duration":"245.108433ms","start":"2026-05-16T02:15:00.932141Z","end":"2026-05-16T02:15:01.177249Z","steps":["trace[1967384211] 'process raft request' (duration: 244.851258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:01.177418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.181114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:15:01.177439Z","caller":"traceutil/trace.go:171","msg":"trace[1576583962] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:26204; }","duration":"162.246686ms","start":"2026-05-16T02:15:01.015184Z","end":"2026-05-16T02:15:01.17743Z","steps":["trace[1576583962] 'agreement among raft nodes before linearized reading' (duration: 162.199315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:01.177571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.746996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/heat-engine-cleaner\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-05-16T02:15:01.177584Z","caller":"traceutil/trace.go:171","msg":"trace[279668342] range","detail":"{range_begin:/registry/serviceaccounts/openstack/heat-engine-cleaner; range_end:; response_count:1; response_revision:26204; }","duration":"199.802226ms","start":"2026-05-16T02:15:00.977778Z","end":"2026-05-16T02:15:01.17758Z","steps":["trace[279668342] 'agreement among raft nodes before linearized reading' (duration: 199.742665ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:01.412143Z","caller":"traceutil/trace.go:171","msg":"trace[1249343323] linearizableReadLoop","detail":"{readStateIndex:27070; appliedIndex:27069; }","duration":"195.303994ms","start":"2026-05-16T02:15:01.216816Z","end":"2026-05-16T02:15:01.41212Z","steps":["trace[1249343323] 'read index received' (duration: 98.979674ms)","trace[1249343323] 'applied index is now lower than readState.Index' (duration: 96.323ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:15:01.412252Z","caller":"traceutil/trace.go:171","msg":"trace[1390315878] transaction","detail":"{read_only:false; response_revision:26205; number_of_response:1; }","duration":"227.327473ms","start":"2026-05-16T02:15:01.184884Z","end":"2026-05-16T02:15:01.412211Z","steps":["trace[1390315878] 'process raft request' (duration: 130.896861ms)","trace[1390315878] 'compare' (duration: 96.238258ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:15:01.412324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.492448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-16T02:15:01.412348Z","caller":"traceutil/trace.go:171","msg":"trace[1811003748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26205; }","duration":"195.56488ms","start":"2026-05-16T02:15:01.216774Z","end":"2026-05-16T02:15:01.412339Z","steps":["trace[1811003748] 'agreement among raft nodes before linearized reading' (duration: 195.444078ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:01.444573Z","caller":"traceutil/trace.go:171","msg":"trace[786611612] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"130.41756ms","start":"2026-05-16T02:15:01.314135Z","end":"2026-05-16T02:15:01.444552Z","steps":["trace[786611612] 'process raft request' (duration: 130.381129ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:01.444633Z","caller":"traceutil/trace.go:171","msg":"trace[290002421] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"143.672749ms","start":"2026-05-16T02:15:01.300921Z","end":"2026-05-16T02:15:01.444594Z","steps":["trace[290002421] 'process raft request' (duration: 143.457824ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:01.444775Z","caller":"traceutil/trace.go:171","msg":"trace[711551317] transaction","detail":"{read_only:false; response_revision:26207; number_of_response:1; }","duration":"142.73159ms","start":"2026-05-16T02:15:01.302019Z","end":"2026-05-16T02:15:01.444751Z","steps":["trace[711551317] 'process raft request' (duration: 142.463354ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:01.694529Z","caller":"traceutil/trace.go:171","msg":"trace[493809218] transaction","detail":"{read_only:false; response_revision:26210; number_of_response:1; }","duration":"358.254373ms","start":"2026-05-16T02:15:01.336248Z","end":"2026-05-16T02:15:01.694502Z","steps":["trace[493809218] 'process raft request' (duration: 358.180902ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:01.694604Z","caller":"traceutil/trace.go:171","msg":"trace[979722186] transaction","detail":"{read_only:false; response_revision:26209; number_of_response:1; }","duration":"377.709898ms","start":"2026-05-16T02:15:01.316861Z","end":"2026-05-16T02:15:01.694571Z","steps":["trace[979722186] 'process raft request' (duration: 334.506153ms)","trace[979722186] 'compare' (duration: 42.95052ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:15:01.69468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:15:01.336222Z","time spent":"358.377946ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T02:15:01.694757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:15:01.316813Z","time spent":"377.859321ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-16T02:15:01.694871Z","caller":"traceutil/trace.go:171","msg":"trace[663190606] linearizableReadLoop","detail":"{readStateIndex:27075; appliedIndex:27071; }","duration":"282.638362ms","start":"2026-05-16T02:15:01.412198Z","end":"2026-05-16T02:15:01.694837Z","steps":["trace[663190606] 'read index received' (duration: 32.286123ms)","trace[663190606] 'applied index is now lower than readState.Index' (duration: 250.350369ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:15:01.695165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.340151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-16T02:15:01.695196Z","caller":"traceutil/trace.go:171","msg":"trace[528803633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26211; }","duration":"381.379653ms","start":"2026-05-16T02:15:01.313807Z","end":"2026-05-16T02:15:01.695186Z","steps":["trace[528803633] 'agreement among raft nodes before linearized reading' (duration: 381.242689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:01.695199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.310343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-16T02:15:01.695217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:15:01.313802Z","time spent":"381.409824ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-16T02:15:01.695237Z","caller":"traceutil/trace.go:171","msg":"trace[1608604931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26211; }","duration":"387.357654ms","start":"2026-05-16T02:15:01.30787Z","end":"2026-05-16T02:15:01.695228Z","steps":["trace[1608604931] 'agreement among raft nodes before linearized reading' (duration: 387.170109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:01.695267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:15:01.307866Z","time spent":"387.395324ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-16T02:15:01.69526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.799511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-16T02:15:01.695346Z","caller":"traceutil/trace.go:171","msg":"trace[1177123062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26211; }","duration":"383.915003ms","start":"2026-05-16T02:15:01.31141Z","end":"2026-05-16T02:15:01.695325Z","steps":["trace[1177123062] 'agreement among raft nodes before linearized reading' (duration: 383.644396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:01.69534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.465887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"warn","ts":"2026-05-16T02:15:01.695393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:15:01.311405Z","time spent":"383.978954ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-16T02:15:01.695428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.09807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-16T02:15:01.695423Z","caller":"traceutil/trace.go:171","msg":"trace[123593669] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26211; }","duration":"274.61358ms","start":"2026-05-16T02:15:01.420789Z","end":"2026-05-16T02:15:01.695403Z","steps":["trace[123593669] 'agreement among raft nodes before linearized reading' (duration: 274.329165ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:01.695456Z","caller":"traceutil/trace.go:171","msg":"trace[791530803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26211; }","duration":"390.14266ms","start":"2026-05-16T02:15:01.305306Z","end":"2026-05-16T02:15:01.695449Z","steps":["trace[791530803] 'agreement among raft nodes before linearized reading' (duration: 390.055488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:01.695481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:15:01.3053Z","time spent":"390.175941ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-16T02:15:01.695592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.788396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-conductor-0.18afe9fa98d839e7\" ","response":"range_response_count:1 size:869"} {"level":"info","ts":"2026-05-16T02:15:01.695656Z","caller":"traceutil/trace.go:171","msg":"trace[40328422] transaction","detail":"{read_only:false; response_revision:26211; number_of_response:1; }","duration":"278.207204ms","start":"2026-05-16T02:15:01.416969Z","end":"2026-05-16T02:15:01.695176Z","steps":["trace[40328422] 'process raft request' (duration: 277.499779ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:01.695672Z","caller":"traceutil/trace.go:171","msg":"trace[1954249589] range","detail":"{range_begin:/registry/events/openstack/magnum-conductor-0.18afe9fa98d839e7; range_end:; response_count:1; response_revision:26211; }","duration":"243.930929ms","start":"2026-05-16T02:15:01.451719Z","end":"2026-05-16T02:15:01.69565Z","steps":["trace[1954249589] 'agreement among raft nodes before linearized reading' (duration: 243.629163ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:01.924569Z","caller":"traceutil/trace.go:171","msg":"trace[1549758543] transaction","detail":"{read_only:false; response_revision:26213; number_of_response:1; }","duration":"224.432784ms","start":"2026-05-16T02:15:01.700111Z","end":"2026-05-16T02:15:01.924544Z","steps":["trace[1549758543] 'process raft request' (duration: 182.287771ms)","trace[1549758543] 'compare' (duration: 41.774166ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:15:01.925Z","caller":"traceutil/trace.go:171","msg":"trace[912607314] transaction","detail":"{read_only:false; response_revision:26214; number_of_response:1; }","duration":"224.764792ms","start":"2026-05-16T02:15:01.700218Z","end":"2026-05-16T02:15:01.924983Z","steps":["trace[912607314] 'process raft request' (duration: 224.289761ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:01.925083Z","caller":"traceutil/trace.go:171","msg":"trace[253495376] linearizableReadLoop","detail":"{readStateIndex:27080; appliedIndex:27077; }","duration":"224.569898ms","start":"2026-05-16T02:15:01.700484Z","end":"2026-05-16T02:15:01.925054Z","steps":["trace[253495376] 'read index received' (duration: 74.52493ms)","trace[253495376] 'applied index is now lower than readState.Index' (duration: 150.043778ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:15:01.92516Z","caller":"traceutil/trace.go:171","msg":"trace[2110982499] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"224.727261ms","start":"2026-05-16T02:15:01.700415Z","end":"2026-05-16T02:15:01.925142Z","steps":["trace[2110982499] 'process raft request' (duration: 224.467686ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:01.925232Z","caller":"traceutil/trace.go:171","msg":"trace[1487945214] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"224.312172ms","start":"2026-05-16T02:15:01.700913Z","end":"2026-05-16T02:15:01.925225Z","steps":["trace[1487945214] 'process raft request' (duration: 224.101728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:01.925334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.822143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-05-16T02:15:01.925375Z","caller":"traceutil/trace.go:171","msg":"trace[161768323] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26216; }","duration":"224.912064ms","start":"2026-05-16T02:15:01.700451Z","end":"2026-05-16T02:15:01.925364Z","steps":["trace[161768323] 'agreement among raft nodes before linearized reading' (duration: 224.717381ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:04.915171Z","caller":"traceutil/trace.go:171","msg":"trace[1495573765] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"150.674901ms","start":"2026-05-16T02:15:04.764462Z","end":"2026-05-16T02:15:04.915137Z","steps":["trace[1495573765] 'process raft request' (duration: 150.454577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:05.290616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.814629ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-16T02:15:05.290777Z","caller":"traceutil/trace.go:171","msg":"trace[723792908] linearizableReadLoop","detail":"{readStateIndex:27109; appliedIndex:27108; }","duration":"145.19304ms","start":"2026-05-16T02:15:05.145515Z","end":"2026-05-16T02:15:05.290709Z","steps":["trace[723792908] 'read index received' (duration: 54.651µs)","trace[723792908] 'applied index is now lower than readState.Index' (duration: 145.136549ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:15:05.290989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.437285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:15:05.291074Z","caller":"traceutil/trace.go:171","msg":"trace[879153163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26243; }","duration":"145.593508ms","start":"2026-05-16T02:15:05.14546Z","end":"2026-05-16T02:15:05.291054Z","steps":["trace[879153163] 'agreement among raft nodes before linearized reading' (duration: 145.397514ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:06.107338Z","caller":"traceutil/trace.go:171","msg":"trace[1918383092] transaction","detail":"{read_only:false; response_revision:26259; number_of_response:1; }","duration":"162.431429ms","start":"2026-05-16T02:15:05.944881Z","end":"2026-05-16T02:15:06.107313Z","steps":["trace[1918383092] 'process raft request' (duration: 152.16371ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:06.107472Z","caller":"traceutil/trace.go:171","msg":"trace[1716986489] linearizableReadLoop","detail":"{readStateIndex:27126; appliedIndex:27123; }","duration":"160.164303ms","start":"2026-05-16T02:15:05.947282Z","end":"2026-05-16T02:15:06.107446Z","steps":["trace[1716986489] 'read index received' (duration: 77.111922ms)","trace[1716986489] 'applied index is now lower than readState.Index' (duration: 83.051181ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:15:06.107495Z","caller":"traceutil/trace.go:171","msg":"trace[1448756561] transaction","detail":"{read_only:false; response_revision:26260; number_of_response:1; }","duration":"162.537231ms","start":"2026-05-16T02:15:05.944928Z","end":"2026-05-16T02:15:06.107465Z","steps":["trace[1448756561] 'process raft request' (duration: 162.286835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:06.107692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.086372ms","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:6"} {"level":"info","ts":"2026-05-16T02:15:06.107727Z","caller":"traceutil/trace.go:171","msg":"trace[776657214] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:26260; }","duration":"118.177584ms","start":"2026-05-16T02:15:05.989537Z","end":"2026-05-16T02:15:06.107715Z","steps":["trace[776657214] 'agreement among raft nodes before linearized reading' (duration: 118.102172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:06.107727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.419339ms","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-05-16T02:15:06.107761Z","caller":"traceutil/trace.go:171","msg":"trace[1001373658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26260; }","duration":"160.484701ms","start":"2026-05-16T02:15:05.947267Z","end":"2026-05-16T02:15:06.107751Z","steps":["trace[1001373658] 'agreement among raft nodes before linearized reading' (duration: 160.316827ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:10.349467Z","caller":"traceutil/trace.go:171","msg":"trace[739978601] transaction","detail":"{read_only:false; response_revision:26296; number_of_response:1; }","duration":"182.519116ms","start":"2026-05-16T02:15:10.166893Z","end":"2026-05-16T02:15:10.349412Z","steps":["trace[739978601] 'process raft request' (duration: 114.57652ms)","trace[739978601] 'compare' (duration: 67.67327ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:15:10.349485Z","caller":"traceutil/trace.go:171","msg":"trace[2036964798] transaction","detail":"{read_only:false; response_revision:26297; number_of_response:1; }","duration":"182.533626ms","start":"2026-05-16T02:15:10.166913Z","end":"2026-05-16T02:15:10.349447Z","steps":["trace[2036964798] 'process raft request' (duration: 182.364053ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:10.34954Z","caller":"traceutil/trace.go:171","msg":"trace[1936498103] linearizableReadLoop","detail":"{readStateIndex:27164; appliedIndex:27161; }","duration":"178.188338ms","start":"2026-05-16T02:15:10.171289Z","end":"2026-05-16T02:15:10.349477Z","steps":["trace[1936498103] 'read index received' (duration: 59.865372ms)","trace[1936498103] 'applied index is now lower than readState.Index' (duration: 118.321226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:15:10.34976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.446464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-16T02:15:10.349836Z","caller":"traceutil/trace.go:171","msg":"trace[772169804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26297; }","duration":"178.553966ms","start":"2026-05-16T02:15:10.171266Z","end":"2026-05-16T02:15:10.34982Z","steps":["trace[772169804] 'agreement among raft nodes before linearized reading' (duration: 178.322991ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:12.207835Z","caller":"traceutil/trace.go:171","msg":"trace[132679588] transaction","detail":"{read_only:false; response_revision:26312; number_of_response:1; }","duration":"146.140348ms","start":"2026-05-16T02:15:12.061645Z","end":"2026-05-16T02:15:12.207785Z","steps":["trace[132679588] 'process raft request' (duration: 145.929534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:12.700367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.251392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-16T02:15:12.700671Z","caller":"traceutil/trace.go:171","msg":"trace[1623139392] transaction","detail":"{read_only:false; response_revision:26315; number_of_response:1; }","duration":"243.695224ms","start":"2026-05-16T02:15:12.456951Z","end":"2026-05-16T02:15:12.700646Z","steps":["trace[1623139392] 'process raft request' (duration: 130.079204ms)","trace[1623139392] 'compare' (duration: 113.12363ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:15:12.700969Z","caller":"traceutil/trace.go:171","msg":"trace[787495943] transaction","detail":"{read_only:false; response_revision:26316; number_of_response:1; }","duration":"243.906238ms","start":"2026-05-16T02:15:12.45705Z","end":"2026-05-16T02:15:12.700957Z","steps":["trace[787495943] 'process raft request' (duration: 243.427849ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:12.70124Z","caller":"traceutil/trace.go:171","msg":"trace[1406629989] linearizableReadLoop","detail":"{readStateIndex:27183; appliedIndex:27181; }","duration":"208.045332ms","start":"2026-05-16T02:15:12.49249Z","end":"2026-05-16T02:15:12.700536Z","steps":["trace[1406629989] 'read index received' (duration: 94.552694ms)","trace[1406629989] 'applied index is now lower than readState.Index' (duration: 113.491538ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:15:12.701382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.788273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:15:12.701434Z","caller":"traceutil/trace.go:171","msg":"trace[1596823313] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:26316; }","duration":"191.898815ms","start":"2026-05-16T02:15:12.509519Z","end":"2026-05-16T02:15:12.701418Z","steps":["trace[1596823313] 'agreement among raft nodes before linearized reading' (duration: 191.807844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:12.70156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.053252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-16T02:15:12.701683Z","caller":"traceutil/trace.go:171","msg":"trace[209557091] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26316; }","duration":"209.205935ms","start":"2026-05-16T02:15:12.49244Z","end":"2026-05-16T02:15:12.701646Z","steps":["trace[209557091] 'agreement among raft nodes before linearized reading' (duration: 208.860718ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:28.242029Z","caller":"traceutil/trace.go:171","msg":"trace[496260250] transaction","detail":"{read_only:false; response_revision:26456; number_of_response:1; }","duration":"117.142732ms","start":"2026-05-16T02:15:28.124832Z","end":"2026-05-16T02:15:28.241975Z","steps":["trace[496260250] 'process raft request' (duration: 44.180074ms)","trace[496260250] 'compare' (duration: 72.434217ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:15:33.891998Z","caller":"traceutil/trace.go:171","msg":"trace[446260112] linearizableReadLoop","detail":"{readStateIndex:27369; appliedIndex:27366; }","duration":"282.47678ms","start":"2026-05-16T02:15:33.609498Z","end":"2026-05-16T02:15:33.891974Z","steps":["trace[446260112] 'read index received' (duration: 20.333102ms)","trace[446260112] 'applied index is now lower than readState.Index' (duration: 262.142608ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:15:33.89218Z","caller":"traceutil/trace.go:171","msg":"trace[215277091] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"288.569572ms","start":"2026-05-16T02:15:33.603547Z","end":"2026-05-16T02:15:33.892117Z","steps":["trace[215277091] 'process raft request' (duration: 288.309007ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:33.892482Z","caller":"traceutil/trace.go:171","msg":"trace[319136915] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"300.126626ms","start":"2026-05-16T02:15:33.592333Z","end":"2026-05-16T02:15:33.89246Z","steps":["trace[319136915] 'process raft request' (duration: 258.360301ms)","trace[319136915] 'compare' (duration: 40.755085ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:15:33.892658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T02:15:33.592306Z","time spent":"300.227139ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T02:15:33.89318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.676393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:15:33.893263Z","caller":"traceutil/trace.go:171","msg":"trace[2146810015] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:26498; }","duration":"283.834296ms","start":"2026-05-16T02:15:33.609408Z","end":"2026-05-16T02:15:33.893242Z","steps":["trace[2146810015] 'agreement among raft nodes before linearized reading' (duration: 282.686713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T02:15:33.89869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.636783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-16T02:15:33.898744Z","caller":"traceutil/trace.go:171","msg":"trace[1904422072] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:26500; }","duration":"243.720815ms","start":"2026-05-16T02:15:33.655008Z","end":"2026-05-16T02:15:33.898728Z","steps":["trace[1904422072] 'agreement among raft nodes before linearized reading' (duration: 243.616833ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:15:42.661845Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006bb3.wal"} {"level":"info","ts":"2026-05-16T02:17:05.737615Z","caller":"traceutil/trace.go:171","msg":"trace[1124372378] linearizableReadLoop","detail":"{readStateIndex:28358; appliedIndex:28357; }","duration":"149.725222ms","start":"2026-05-16T02:17:05.587861Z","end":"2026-05-16T02:17:05.737586Z","steps":["trace[1124372378] 'read index received' (duration: 149.509238ms)","trace[1124372378] 'applied index is now lower than readState.Index' (duration: 214.784µs)"],"step_count":2} {"level":"warn","ts":"2026-05-16T02:17:05.737991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.11044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-16T02:17:05.738134Z","caller":"traceutil/trace.go:171","msg":"trace[768547336] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:27466; }","duration":"150.299514ms","start":"2026-05-16T02:17:05.587818Z","end":"2026-05-16T02:17:05.738117Z","steps":["trace[768547336] 'agreement among raft nodes before linearized reading' (duration: 149.985178ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:17:05.73859Z","caller":"traceutil/trace.go:171","msg":"trace[758628903] transaction","detail":"{read_only:false; response_revision:27466; number_of_response:1; }","duration":"174.217237ms","start":"2026-05-16T02:17:05.564357Z","end":"2026-05-16T02:17:05.738574Z","steps":["trace[758628903] 'process raft request' (duration: 173.058934ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:18:26.662166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25228} {"level":"info","ts":"2026-05-16T02:18:26.80428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25228,"took":"136.950633ms","hash":1195585204,"current-db-size-bytes":59174912,"current-db-size":"59 MB","current-db-size-in-use-bytes":38117376,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-05-16T02:18:26.804341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1195585204,"revision":25228,"compact-revision":22011} {"level":"info","ts":"2026-05-16T02:18:47.981047Z","caller":"traceutil/trace.go:171","msg":"trace[1627526860] transaction","detail":"{read_only:false; response_revision:28451; number_of_response:1; }","duration":"141.139387ms","start":"2026-05-16T02:18:47.839874Z","end":"2026-05-16T02:18:47.981013Z","steps":["trace[1627526860] 'process raft request' (duration: 92.780369ms)","trace[1627526860] 'compare' (duration: 47.92135ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T02:18:47.981889Z","caller":"traceutil/trace.go:171","msg":"trace[816009629] transaction","detail":"{read_only:false; response_revision:28452; number_of_response:1; }","duration":"140.717759ms","start":"2026-05-16T02:18:47.841098Z","end":"2026-05-16T02:18:47.981816Z","steps":["trace[816009629] 'process raft request' (duration: 139.8143ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:18:56.118016Z","caller":"traceutil/trace.go:171","msg":"trace[1092005269] transaction","detail":"{read_only:false; response_revision:28516; number_of_response:1; }","duration":"108.364434ms","start":"2026-05-16T02:18:56.009599Z","end":"2026-05-16T02:18:56.117964Z","steps":["trace[1092005269] 'process raft request' (duration: 108.054468ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T02:19:53.694252Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-16T02:19:53.69912Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-16T02:19:53.699232Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-16T02:23:26.671352Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28199} {"level":"info","ts":"2026-05-16T02:23:26.814185Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28199,"took":"137.379991ms","hash":2765051746,"current-db-size-bytes":59174912,"current-db-size":"59 MB","current-db-size-in-use-bytes":32546816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-16T02:23:26.814285Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2765051746,"revision":28199,"compact-revision":25228} {"level":"info","ts":"2026-05-16T02:28:26.677126Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30673} {"level":"info","ts":"2026-05-16T02:28:26.820633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30673,"took":"137.873561ms","hash":2079626772,"current-db-size-bytes":59174912,"current-db-size":"59 MB","current-db-size-in-use-bytes":30494720,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-16T02:28:26.82073Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2079626772,"revision":30673,"compact-revision":28199} {"level":"info","ts":"2026-05-16T02:33:26.684479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32941} {"level":"info","ts":"2026-05-16T02:33:26.834551Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32941,"took":"144.260231ms","hash":2067977513,"current-db-size-bytes":60215296,"current-db-size":"60 MB","current-db-size-in-use-bytes":30371840,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-16T02:33:26.834632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2067977513,"revision":32941,"compact-revision":30673} {"level":"info","ts":"2026-05-16T02:38:26.691214Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35214} {"level":"info","ts":"2026-05-16T02:38:26.83881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35214,"took":"142.696429ms","hash":3815174774,"current-db-size-bytes":60215296,"current-db-size":"60 MB","current-db-size-in-use-bytes":31309824,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-16T02:38:26.840093Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3815174774,"revision":35214,"compact-revision":32941} {"level":"info","ts":"2026-05-16T02:41:12.740878Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-16T02:41:12.746256Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-16T02:41:12.746413Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-16T02:43:26.710856Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37489} {"level":"info","ts":"2026-05-16T02:43:26.838129Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37489,"took":"122.343717ms","hash":2512832595,"current-db-size-bytes":60641280,"current-db-size":"61 MB","current-db-size-in-use-bytes":31436800,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-16T02:43:26.838205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2512832595,"revision":37489,"compact-revision":35214} {"level":"info","ts":"2026-05-16T02:48:26.717613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39774} {"level":"info","ts":"2026-05-16T02:48:26.843212Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39774,"took":"120.495888ms","hash":1216481228,"current-db-size-bytes":60641280,"current-db-size":"61 MB","current-db-size-in-use-bytes":31014912,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-16T02:48:26.843307Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1216481228,"revision":39774,"compact-revision":37489}