{"level":"warn","ts":"2026-07-13T12:31:03.084661Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-13T12:31:03.084746Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-07-13T12:31:03.084801Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-13T12:31:03.084815Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-13T12:31:03.08483Z","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-07-13T12:31:03.085407Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-07-13T12:31:03.085479Z","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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-07-13T12:31:03.0895Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.738244ms"} {"level":"info","ts":"2026-07-13T12:31:03.094748Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-07-13T12:31:03.094824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-07-13T12:31:03.094842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-07-13T12:31:03.094861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-13T12:31:03.094875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-07-13T12:31:03.094914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-07-13T12:31:03.105347Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-13T12:31:03.106807Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-13T12:31:03.108183Z","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-07-13T12:31:03.111212Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-13T12:31:03.111798Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-13T12:31:03.111982Z","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-07-13T12:31:03.112027Z","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-07-13T12:31:03.112037Z","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-07-13T12:31:03.112472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-07-13T12:31:03.114248Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-13T12:31:03.116414Z","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-07-13T12:31:03.116508Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-13T12:31:03.116536Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-13T12:31:03.116709Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-13T12:31:03.116731Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-13T12:31:03.295648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-07-13T12:31:03.295715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-13T12:31:03.295741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-07-13T12:31:03.295762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-07-13T12:31:03.295774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-13T12:31:03.295791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-07-13T12:31:03.295805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-13T12:31:03.296895Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-07-13T12:31:03.296949Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-13T12:31:03.296989Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-13T12:31:03.297164Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T12:31:03.297332Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-13T12:31:03.297373Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-13T12:31:03.29803Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T12:31:03.298137Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T12:31:03.298174Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T12:31:03.300507Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-07-13T12:31:03.301048Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-13T12:31:21.889261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.471477ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-13T12:31:21.889429Z","caller":"traceutil/trace.go:171","msg":"trace[1751227122] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"188.561463ms","start":"2026-07-13T12:31:21.70081Z","end":"2026-07-13T12:31:21.889371Z","steps":["trace[1751227122] 'read index received' (duration: 80.851869ms)","trace[1751227122] 'applied index is now lower than readState.Index' (duration: 107.708154ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T12:31:21.889666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-13T12:31:21.889707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.35692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T12:31:21.889762Z","caller":"traceutil/trace.go:171","msg":"trace[610965054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:382; }","duration":"188.991705ms","start":"2026-07-13T12:31:21.700749Z","end":"2026-07-13T12:31:21.88974Z","steps":["trace[610965054] 'agreement among raft nodes before linearized reading' (duration: 188.744608ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:31:21.889807Z","caller":"traceutil/trace.go:171","msg":"trace[25490875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:382; }","duration":"119.516985ms","start":"2026-07-13T12:31:21.770265Z","end":"2026-07-13T12:31:21.889782Z","steps":["trace[25490875] 'agreement among raft nodes before linearized reading' (duration: 119.33871ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:31:52.943727Z","caller":"traceutil/trace.go:171","msg":"trace[2032845665] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"173.45213ms","start":"2026-07-13T12:31:52.770226Z","end":"2026-07-13T12:31:52.943678Z","steps":["trace[2032845665] 'read index received' (duration: 146.263236ms)","trace[2032845665] 'applied index is now lower than readState.Index' (duration: 27.187303ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T12:31:52.943883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.646674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T12:31:52.943923Z","caller":"traceutil/trace.go:171","msg":"trace[1842322701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"173.754038ms","start":"2026-07-13T12:31:52.770157Z","end":"2026-07-13T12:31:52.943911Z","steps":["trace[1842322701] 'agreement among raft nodes before linearized reading' (duration: 173.648825ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:31:52.944034Z","caller":"traceutil/trace.go:171","msg":"trace[119747865] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"533.453255ms","start":"2026-07-13T12:31:52.410558Z","end":"2026-07-13T12:31:52.944011Z","steps":["trace[119747865] 'process raft request' (duration: 506.175639ms)","trace[119747865] 'compare' (duration: 26.571636ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T12:31:52.944682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T12:31:52.410521Z","time spent":"533.574888ms","remote":"127.0.0.1:52714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T12:31:53.40244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.077402ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T12:31:53.40272Z","caller":"traceutil/trace.go:171","msg":"trace[1268015114] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:610; }","duration":"207.379161ms","start":"2026-07-13T12:31:53.195299Z","end":"2026-07-13T12:31:53.402678Z","steps":["trace[1268015114] 'range keys from in-memory index tree' (duration: 207.042561ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:31:53.415614Z","caller":"traceutil/trace.go:171","msg":"trace[1636957951] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"408.090535ms","start":"2026-07-13T12:31:53.007501Z","end":"2026-07-13T12:31:53.415591Z","steps":["trace[1636957951] 'process raft request' (duration: 407.928641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:31:53.415748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T12:31:53.007469Z","time spent":"408.208339ms","remote":"127.0.0.1:53114","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-07-13T12:32:09.475044Z","caller":"traceutil/trace.go:171","msg":"trace[1621638499] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"131.265994ms","start":"2026-07-13T12:32:09.34375Z","end":"2026-07-13T12:32:09.475016Z","steps":["trace[1621638499] 'process raft request' (duration: 131.081449ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:32:42.624413Z","caller":"traceutil/trace.go:171","msg":"trace[333209027] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"158.681174ms","start":"2026-07-13T12:32:42.465706Z","end":"2026-07-13T12:32:42.624387Z","steps":["trace[333209027] 'process raft request' (duration: 158.513259ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:33:33.962868Z","caller":"traceutil/trace.go:171","msg":"trace[356071794] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"144.724052ms","start":"2026-07-13T12:33:33.818121Z","end":"2026-07-13T12:33:33.962845Z","steps":["trace[356071794] 'read index received' (duration: 144.493087ms)","trace[356071794] 'applied index is now lower than readState.Index' (duration: 229.755µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T12:33:33.962966Z","caller":"traceutil/trace.go:171","msg":"trace[1889265639] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"148.200071ms","start":"2026-07-13T12:33:33.814729Z","end":"2026-07-13T12:33:33.962929Z","steps":["trace[1889265639] 'process raft request' (duration: 147.948964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:33:33.963471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.302918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-25223610-ce6a-4773-8c8a-f1a00f9dce8d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-13T12:33:33.963652Z","caller":"traceutil/trace.go:171","msg":"trace[1706807908] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-25223610-ce6a-4773-8c8a-f1a00f9dce8d; range_end:; response_count:1; response_revision:1884; }","duration":"145.562024ms","start":"2026-07-13T12:33:33.818053Z","end":"2026-07-13T12:33:33.963615Z","steps":["trace[1706807908] 'agreement among raft nodes before linearized reading' (duration: 144.976879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:33:34.328263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.150464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-13T12:33:34.328541Z","caller":"traceutil/trace.go:171","msg":"trace[1210195332] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1891; }","duration":"170.497413ms","start":"2026-07-13T12:33:34.158014Z","end":"2026-07-13T12:33:34.328511Z","steps":["trace[1210195332] 'count revisions from in-memory index tree' (duration: 170.058992ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:34:24.492839Z","caller":"traceutil/trace.go:171","msg":"trace[763019373] linearizableReadLoop","detail":"{readStateIndex:2302; appliedIndex:2301; }","duration":"132.359273ms","start":"2026-07-13T12:34:24.360436Z","end":"2026-07-13T12:34:24.492795Z","steps":["trace[763019373] 'read index received' (duration: 132.039884ms)","trace[763019373] 'applied index is now lower than readState.Index' (duration: 317.999µs)"],"step_count":2} {"level":"warn","ts":"2026-07-13T12:34:24.49309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.627712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-13T12:34:24.493267Z","caller":"traceutil/trace.go:171","msg":"trace[1214578455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2211; }","duration":"132.800406ms","start":"2026-07-13T12:34:24.360408Z","end":"2026-07-13T12:34:24.493208Z","steps":["trace[1214578455] 'agreement among raft nodes before linearized reading' (duration: 132.498807ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:34:24.493765Z","caller":"traceutil/trace.go:171","msg":"trace[419034722] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"136.568944ms","start":"2026-07-13T12:34:24.356278Z","end":"2026-07-13T12:34:24.492847Z","steps":["trace[419034722] 'process raft request' (duration: 136.242884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:34:24.665824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.940954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T12:34:24.665987Z","caller":"traceutil/trace.go:171","msg":"trace[804490717] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"167.195305ms","start":"2026-07-13T12:34:24.498763Z","end":"2026-07-13T12:34:24.665958Z","steps":["trace[804490717] 'process raft request' (duration: 54.947632ms)","trace[804490717] 'compare' (duration: 111.355457ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T12:34:24.914064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.260302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T12:34:24.914165Z","caller":"traceutil/trace.go:171","msg":"trace[1472387596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2212; }","duration":"144.449008ms","start":"2026-07-13T12:34:24.769693Z","end":"2026-07-13T12:34:24.914142Z","steps":["trace[1472387596] 'range keys from in-memory index tree' (duration: 144.136819ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:35:13.538651Z","caller":"traceutil/trace.go:171","msg":"trace[1903168333] linearizableReadLoop","detail":"{readStateIndex:2755; appliedIndex:2754; }","duration":"175.075998ms","start":"2026-07-13T12:35:13.363532Z","end":"2026-07-13T12:35:13.538608Z","steps":["trace[1903168333] 'read index received' (duration: 156.630914ms)","trace[1903168333] 'applied index is now lower than readState.Index' (duration: 18.437904ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T12:35:13.538636Z","caller":"traceutil/trace.go:171","msg":"trace[1316336455] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"209.233439ms","start":"2026-07-13T12:35:13.329353Z","end":"2026-07-13T12:35:13.538587Z","steps":["trace[1316336455] 'process raft request' (duration: 190.746793ms)","trace[1316336455] 'compare' (duration: 18.30033ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T12:35:13.538858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.315896ms","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-07-13T12:35:13.538911Z","caller":"traceutil/trace.go:171","msg":"trace[190169247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2649; }","duration":"175.419789ms","start":"2026-07-13T12:35:13.363476Z","end":"2026-07-13T12:35:13.538896Z","steps":["trace[190169247] 'agreement among raft nodes before linearized reading' (duration: 175.224323ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:35:47.264602Z","caller":"traceutil/trace.go:171","msg":"trace[1762580280] linearizableReadLoop","detail":"{readStateIndex:2969; appliedIndex:2968; }","duration":"127.401161ms","start":"2026-07-13T12:35:47.137173Z","end":"2026-07-13T12:35:47.264575Z","steps":["trace[1762580280] 'read index received' (duration: 127.243406ms)","trace[1762580280] 'applied index is now lower than readState.Index' (duration: 156.695µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T12:35:47.264658Z","caller":"traceutil/trace.go:171","msg":"trace[190731458] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"195.001372ms","start":"2026-07-13T12:35:47.069622Z","end":"2026-07-13T12:35:47.264623Z","steps":["trace[190731458] 'process raft request' (duration: 194.797496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:35:47.264804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.728068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-13T12:35:47.264834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.628837ms","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-07-13T12:35:47.264847Z","caller":"traceutil/trace.go:171","msg":"trace[604484807] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2854; }","duration":"107.838861ms","start":"2026-07-13T12:35:47.156999Z","end":"2026-07-13T12:35:47.264837Z","steps":["trace[604484807] 'agreement among raft nodes before linearized reading' (duration: 107.738988ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:35:47.264979Z","caller":"traceutil/trace.go:171","msg":"trace[1351942922] 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:2854; }","duration":"127.810992ms","start":"2026-07-13T12:35:47.137125Z","end":"2026-07-13T12:35:47.264936Z","steps":["trace[1351942922] 'agreement among raft nodes before linearized reading' (duration: 127.565575ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:38:22.964515Z","caller":"traceutil/trace.go:171","msg":"trace[1209397457] transaction","detail":"{read_only:false; response_revision:3874; number_of_response:1; }","duration":"189.614398ms","start":"2026-07-13T12:38:22.774877Z","end":"2026-07-13T12:38:22.964491Z","steps":["trace[1209397457] 'process raft request' (duration: 189.472654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:39:53.253408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.035703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T12:39:53.253637Z","caller":"traceutil/trace.go:171","msg":"trace[524954503] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"178.845805ms","start":"2026-07-13T12:39:53.074695Z","end":"2026-07-13T12:39:53.253541Z","steps":["trace[524954503] 'process raft request' (duration: 77.531304ms)","trace[524954503] 'compare' (duration: 100.706244ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T12:40:04.514421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.097371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-13T12:40:04.514808Z","caller":"traceutil/trace.go:171","msg":"trace[1684504446] linearizableReadLoop","detail":"{readStateIndex:5519; appliedIndex:5518; }","duration":"132.823454ms","start":"2026-07-13T12:40:04.381933Z","end":"2026-07-13T12:40:04.514757Z","steps":["trace[1684504446] 'read index received' (duration: 54.821µs)","trace[1684504446] 'applied index is now lower than readState.Index' (duration: 132.762413ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T12:40:04.51495Z","caller":"traceutil/trace.go:171","msg":"trace[555523550] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"128.937545ms","start":"2026-07-13T12:40:04.385987Z","end":"2026-07-13T12:40:04.514924Z","steps":["trace[555523550] 'process raft request' (duration: 128.866773ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:40:04.514982Z","caller":"traceutil/trace.go:171","msg":"trace[397698834] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"132.312659ms","start":"2026-07-13T12:40:04.382638Z","end":"2026-07-13T12:40:04.514951Z","steps":["trace[397698834] 'process raft request' (duration: 132.056542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:40:04.515092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.137763ms","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-07-13T12:40:04.515184Z","caller":"traceutil/trace.go:171","msg":"trace[1759720339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5316; }","duration":"133.266427ms","start":"2026-07-13T12:40:04.381876Z","end":"2026-07-13T12:40:04.515142Z","steps":["trace[1759720339] 'agreement among raft nodes before linearized reading' (duration: 132.986689ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:40:04.515314Z","caller":"traceutil/trace.go:171","msg":"trace[1916212777] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"246.355827ms","start":"2026-07-13T12:40:04.268342Z","end":"2026-07-13T12:40:04.514697Z","steps":["trace[1916212777] 'process raft request' (duration: 76.799433ms)","trace[1916212777] 'compare' (duration: 168.182566ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T12:40:12.773243Z","caller":"traceutil/trace.go:171","msg":"trace[882859185] transaction","detail":"{read_only:false; response_revision:5492; number_of_response:1; }","duration":"163.746441ms","start":"2026-07-13T12:40:12.60948Z","end":"2026-07-13T12:40:12.773226Z","steps":["trace[882859185] 'process raft request' (duration: 97.217696ms)","trace[882859185] 'compare' (duration: 65.879257ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T12:40:12.773073Z","caller":"traceutil/trace.go:171","msg":"trace[1287616481] linearizableReadLoop","detail":"{readStateIndex:5699; appliedIndex:5698; }","duration":"141.764425ms","start":"2026-07-13T12:40:12.631274Z","end":"2026-07-13T12:40:12.773039Z","steps":["trace[1287616481] 'read index received' (duration: 75.325002ms)","trace[1287616481] 'applied index is now lower than readState.Index' (duration: 66.437993ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T12:40:12.773499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.199587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-13T12:40:12.773627Z","caller":"traceutil/trace.go:171","msg":"trace[333690994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5492; }","duration":"142.369502ms","start":"2026-07-13T12:40:12.63123Z","end":"2026-07-13T12:40:12.773599Z","steps":["trace[333690994] 'agreement among raft nodes before linearized reading' (duration: 142.090254ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:40:12.887004Z","caller":"traceutil/trace.go:171","msg":"trace[521577041] linearizableReadLoop","detail":"{readStateIndex:5701; appliedIndex:5699; }","duration":"113.545124ms","start":"2026-07-13T12:40:12.773435Z","end":"2026-07-13T12:40:12.886981Z","steps":["trace[521577041] 'read index received' (duration: 25.68818ms)","trace[521577041] 'applied index is now lower than readState.Index' (duration: 87.855744ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T12:40:12.887035Z","caller":"traceutil/trace.go:171","msg":"trace[638096169] transaction","detail":"{read_only:false; response_revision:5494; number_of_response:1; }","duration":"160.733357ms","start":"2026-07-13T12:40:12.726263Z","end":"2026-07-13T12:40:12.886996Z","steps":["trace[638096169] 'process raft request' (duration: 157.784775ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:40:12.887071Z","caller":"traceutil/trace.go:171","msg":"trace[1812596969] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"108.510203ms","start":"2026-07-13T12:40:12.778527Z","end":"2026-07-13T12:40:12.887037Z","steps":["trace[1812596969] 'process raft request' (duration: 108.40243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:40:12.887192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.055112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T12:40:12.887237Z","caller":"traceutil/trace.go:171","msg":"trace[1692937796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5495; }","duration":"117.168736ms","start":"2026-07-13T12:40:12.770055Z","end":"2026-07-13T12:40:12.887224Z","steps":["trace[1692937796] 'agreement among raft nodes before linearized reading' (duration: 117.080363ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:41:03.55738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2944} {"level":"info","ts":"2026-07-13T12:41:03.630186Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2944,"took":"71.178231ms","hash":3759767019,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":33832960,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-13T12:41:03.630269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3759767019,"revision":2944,"compact-revision":-1} {"level":"info","ts":"2026-07-13T12:46:03.56461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5993} {"level":"info","ts":"2026-07-13T12:46:03.656364Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5993,"took":"89.901876ms","hash":264168647,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":21831680,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-13T12:46:03.656435Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":264168647,"revision":5993,"compact-revision":2944} {"level":"warn","ts":"2026-07-13T12:46:31.120335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.453501ms","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-07-13T12:46:31.120461Z","caller":"traceutil/trace.go:171","msg":"trace[838325888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8388; }","duration":"253.640605ms","start":"2026-07-13T12:46:30.866795Z","end":"2026-07-13T12:46:31.120436Z","steps":["trace[838325888] 'range keys from in-memory index tree' (duration: 253.256325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:46:31.120738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.970878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-13T12:46:31.120785Z","caller":"traceutil/trace.go:171","msg":"trace[486637324] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8388; }","duration":"237.071192ms","start":"2026-07-13T12:46:30.883699Z","end":"2026-07-13T12:46:31.12077Z","steps":["trace[486637324] 'count revisions from in-memory index tree' (duration: 236.832575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:46:31.12035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.827753ms","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-07-13T12:46:31.121101Z","caller":"traceutil/trace.go:171","msg":"trace[61649776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8388; }","duration":"117.645256ms","start":"2026-07-13T12:46:31.00344Z","end":"2026-07-13T12:46:31.121085Z","steps":["trace[61649776] 'range keys from in-memory index tree' (duration: 116.594057ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:46:43.382382Z","caller":"traceutil/trace.go:171","msg":"trace[1574284386] linearizableReadLoop","detail":"{readStateIndex:8755; appliedIndex:8754; }","duration":"164.236624ms","start":"2026-07-13T12:46:43.218119Z","end":"2026-07-13T12:46:43.382356Z","steps":["trace[1574284386] 'read index received' (duration: 164.055539ms)","trace[1574284386] 'applied index is now lower than readState.Index' (duration: 179.655µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T12:46:43.382476Z","caller":"traceutil/trace.go:171","msg":"trace[630727115] transaction","detail":"{read_only:false; response_revision:8451; number_of_response:1; }","duration":"166.496216ms","start":"2026-07-13T12:46:43.215924Z","end":"2026-07-13T12:46:43.38242Z","steps":["trace[630727115] 'process raft request' (duration: 166.251869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:46:43.382613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.47466ms","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-07-13T12:46:43.382716Z","caller":"traceutil/trace.go:171","msg":"trace[566982717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8451; }","duration":"164.614204ms","start":"2026-07-13T12:46:43.218077Z","end":"2026-07-13T12:46:43.382691Z","steps":["trace[566982717] 'agreement among raft nodes before linearized reading' (duration: 164.382238ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:46:43.586824Z","caller":"traceutil/trace.go:171","msg":"trace[854178197] transaction","detail":"{read_only:false; response_revision:8452; number_of_response:1; }","duration":"199.06643ms","start":"2026-07-13T12:46:43.38772Z","end":"2026-07-13T12:46:43.586786Z","steps":["trace[854178197] 'process raft request' (duration: 175.090322ms)","trace[854178197] 'compare' (duration: 23.752112ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T12:46:49.361011Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000225e.wal"} {"level":"warn","ts":"2026-07-13T12:47:11.747446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.824205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T12:47:11.747486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.225701ms","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-07-13T12:47:11.747553Z","caller":"traceutil/trace.go:171","msg":"trace[488801438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8657; }","duration":"227.341434ms","start":"2026-07-13T12:47:11.520198Z","end":"2026-07-13T12:47:11.74754Z","steps":["trace[488801438] 'range keys from in-memory index tree' (duration: 227.022896ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:47:11.74755Z","caller":"traceutil/trace.go:171","msg":"trace[1491853664] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8657; }","duration":"179.01996ms","start":"2026-07-13T12:47:11.568508Z","end":"2026-07-13T12:47:11.747528Z","steps":["trace[1491853664] 'count revisions from in-memory index tree' (duration: 178.716842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:47:24.023383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.520962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T12:47:24.023774Z","caller":"traceutil/trace.go:171","msg":"trace[343073952] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8762; }","duration":"172.435918ms","start":"2026-07-13T12:47:23.851321Z","end":"2026-07-13T12:47:24.023757Z","steps":["trace[343073952] 'process raft request' (duration: 172.258363ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:47:24.023828Z","caller":"traceutil/trace.go:171","msg":"trace[1355927963] transaction","detail":"{read_only:false; response_revision:8761; number_of_response:1; }","duration":"173.688961ms","start":"2026-07-13T12:47:23.850125Z","end":"2026-07-13T12:47:24.023814Z","steps":["trace[1355927963] 'process raft request' (duration: 53.660376ms)","trace[1355927963] 'compare' (duration: 119.421709ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T12:47:24.030065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.216727ms","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-07-13T12:47:24.03012Z","caller":"traceutil/trace.go:171","msg":"trace[573169470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8763; }","duration":"101.313ms","start":"2026-07-13T12:47:23.928793Z","end":"2026-07-13T12:47:24.030106Z","steps":["trace[573169470] 'agreement among raft nodes before linearized reading' (duration: 101.181636ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:47:24.320123Z","caller":"traceutil/trace.go:171","msg":"trace[2036356647] linearizableReadLoop","detail":"{readStateIndex:9084; appliedIndex:9082; }","duration":"261.828819ms","start":"2026-07-13T12:47:24.058268Z","end":"2026-07-13T12:47:24.320097Z","steps":["trace[2036356647] 'read index received' (duration: 261.531601ms)","trace[2036356647] 'applied index is now lower than readState.Index' (duration: 296.368µs)"],"step_count":2} {"level":"warn","ts":"2026-07-13T12:47:24.320275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.979403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-mm69r\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-07-13T12:47:24.320308Z","caller":"traceutil/trace.go:171","msg":"trace[85506855] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-mm69r; range_end:; response_count:1; response_revision:8766; }","duration":"262.056465ms","start":"2026-07-13T12:47:24.058242Z","end":"2026-07-13T12:47:24.320299Z","steps":["trace[85506855] 'agreement among raft nodes before linearized reading' (duration: 261.956983ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:47:24.320466Z","caller":"traceutil/trace.go:171","msg":"trace[764454862] transaction","detail":"{read_only:false; response_revision:8765; number_of_response:1; }","duration":"287.333717ms","start":"2026-07-13T12:47:24.033096Z","end":"2026-07-13T12:47:24.320429Z","steps":["trace[764454862] 'process raft request' (duration: 286.797933ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:47:24.320586Z","caller":"traceutil/trace.go:171","msg":"trace[376392741] transaction","detail":"{read_only:false; response_revision:8766; number_of_response:1; }","duration":"285.215969ms","start":"2026-07-13T12:47:24.035291Z","end":"2026-07-13T12:47:24.320507Z","steps":["trace[376392741] 'process raft request' (duration: 284.751507ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:47:25.250191Z","caller":"traceutil/trace.go:171","msg":"trace[1630388698] linearizableReadLoop","detail":"{readStateIndex:9090; appliedIndex:9089; }","duration":"137.318733ms","start":"2026-07-13T12:47:25.112855Z","end":"2026-07-13T12:47:25.250173Z","steps":["trace[1630388698] 'read index received' (duration: 137.170149ms)","trace[1630388698] 'applied index is now lower than readState.Index' (duration: 147.894µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T12:47:25.250231Z","caller":"traceutil/trace.go:171","msg":"trace[1309839069] transaction","detail":"{read_only:false; response_revision:8772; number_of_response:1; }","duration":"154.693451ms","start":"2026-07-13T12:47:25.095514Z","end":"2026-07-13T12:47:25.250207Z","steps":["trace[1309839069] 'process raft request' (duration: 154.533816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:47:25.250409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.538808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-13T12:47:25.25044Z","caller":"traceutil/trace.go:171","msg":"trace[1263055341] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8772; }","duration":"137.60639ms","start":"2026-07-13T12:47:25.112825Z","end":"2026-07-13T12:47:25.250432Z","steps":["trace[1263055341] 'agreement among raft nodes before linearized reading' (duration: 137.453145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:47:25.250654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.514343ms","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-07-13T12:47:25.250777Z","caller":"traceutil/trace.go:171","msg":"trace[366716980] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8772; }","duration":"132.693817ms","start":"2026-07-13T12:47:25.118062Z","end":"2026-07-13T12:47:25.250755Z","steps":["trace[366716980] 'agreement among raft nodes before linearized reading' (duration: 132.319377ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:48:58.888927Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fed19d9291630e09","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-13T12:48:58.893911Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-13T12:48:58.894025Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-13T12:51:03.57204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8065} {"level":"info","ts":"2026-07-13T12:51:03.634447Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8065,"took":"59.841644ms","hash":1552040160,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":28168192,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-13T12:51:03.634527Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1552040160,"revision":8065,"compact-revision":5993} {"level":"info","ts":"2026-07-13T12:54:00.35283Z","caller":"traceutil/trace.go:171","msg":"trace[1110035779] transaction","detail":"{read_only:false; response_revision:11847; number_of_response:1; }","duration":"108.780331ms","start":"2026-07-13T12:54:00.244018Z","end":"2026-07-13T12:54:00.352798Z","steps":["trace[1110035779] 'process raft request' (duration: 108.510843ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:54:17.540215Z","caller":"traceutil/trace.go:171","msg":"trace[1689871023] transaction","detail":"{read_only:false; response_revision:11945; number_of_response:1; }","duration":"144.639116ms","start":"2026-07-13T12:54:17.39555Z","end":"2026-07-13T12:54:17.540189Z","steps":["trace[1689871023] 'process raft request' (duration: 144.491901ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:54:17.546312Z","caller":"traceutil/trace.go:171","msg":"trace[2001983544] transaction","detail":"{read_only:false; response_revision:11946; number_of_response:1; }","duration":"149.401139ms","start":"2026-07-13T12:54:17.396888Z","end":"2026-07-13T12:54:17.546289Z","steps":["trace[2001983544] 'process raft request' (duration: 149.279945ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:54:18.330706Z","caller":"traceutil/trace.go:171","msg":"trace[1498709842] linearizableReadLoop","detail":"{readStateIndex:12367; appliedIndex:12364; }","duration":"170.993595ms","start":"2026-07-13T12:54:18.159684Z","end":"2026-07-13T12:54:18.330677Z","steps":["trace[1498709842] 'read index received' (duration: 170.767428ms)","trace[1498709842] 'applied index is now lower than readState.Index' (duration: 225.127µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T12:54:18.330761Z","caller":"traceutil/trace.go:171","msg":"trace[256839763] transaction","detail":"{read_only:false; response_revision:11950; number_of_response:1; }","duration":"203.652965ms","start":"2026-07-13T12:54:18.127087Z","end":"2026-07-13T12:54:18.33074Z","steps":["trace[256839763] 'process raft request' (duration: 203.548631ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:54:18.330744Z","caller":"traceutil/trace.go:171","msg":"trace[1819589214] transaction","detail":"{read_only:false; response_revision:11949; number_of_response:1; }","duration":"207.220472ms","start":"2026-07-13T12:54:18.12349Z","end":"2026-07-13T12:54:18.33071Z","steps":["trace[1819589214] 'process raft request' (duration: 207.108468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T12:54:18.331015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.317855ms","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-07-13T12:54:18.331064Z","caller":"traceutil/trace.go:171","msg":"trace[586087105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11950; }","duration":"171.432028ms","start":"2026-07-13T12:54:18.159618Z","end":"2026-07-13T12:54:18.33105Z","steps":["trace[586087105] 'agreement among raft nodes before linearized reading' (duration: 171.213851ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:54:18.330895Z","caller":"traceutil/trace.go:171","msg":"trace[927552067] transaction","detail":"{read_only:false; response_revision:11948; number_of_response:1; }","duration":"209.367396ms","start":"2026-07-13T12:54:18.121457Z","end":"2026-07-13T12:54:18.330824Z","steps":["trace[927552067] 'process raft request' (duration: 208.959694ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T12:56:03.578537Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10473} {"level":"info","ts":"2026-07-13T12:56:03.653774Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10473,"took":"72.981314ms","hash":2762911767,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":26423296,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-13T12:56:03.653837Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2762911767,"revision":10473,"compact-revision":8065} {"level":"warn","ts":"2026-07-13T13:00:16.796704Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514806485664893,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-13T13:00:17.002051Z","caller":"traceutil/trace.go:171","msg":"trace[641159928] linearizableReadLoop","detail":"{readStateIndex:14841; appliedIndex:14840; }","duration":"705.824558ms","start":"2026-07-13T13:00:16.296203Z","end":"2026-07-13T13:00:17.002027Z","steps":["trace[641159928] 'read index received' (duration: 705.587301ms)","trace[641159928] 'applied index is now lower than readState.Index' (duration: 236.507µs)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:00:17.002389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.598388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-13T13:00:17.002419Z","caller":"traceutil/trace.go:171","msg":"trace[1593945714] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:14342; }","duration":"653.69235ms","start":"2026-07-13T13:00:16.348717Z","end":"2026-07-13T13:00:17.002409Z","steps":["trace[1593945714] 'agreement among raft nodes before linearized reading' (duration: 653.609528ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:00:17.002398Z","caller":"traceutil/trace.go:171","msg":"trace[1930136600] transaction","detail":"{read_only:false; response_revision:14342; number_of_response:1; }","duration":"824.520355ms","start":"2026-07-13T13:00:16.177852Z","end":"2026-07-13T13:00:17.002373Z","steps":["trace[1930136600] 'process raft request' (duration: 824.026442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:00:17.002446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:00:16.348696Z","time spent":"653.743541ms","remote":"127.0.0.1:37438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":4,"response size":30,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-13T13:00:17.002442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.215879ms","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-07-13T13:00:17.002523Z","caller":"traceutil/trace.go:171","msg":"trace[1268333574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14342; }","duration":"706.334052ms","start":"2026-07-13T13:00:16.296167Z","end":"2026-07-13T13:00:17.002501Z","steps":["trace[1268333574] 'agreement among raft nodes before linearized reading' (duration: 706.058644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:00:17.002537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:00:16.177823Z","time spent":"824.641939ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T13:00:17.002557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:00:16.296155Z","time spent":"706.397074ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-13T13:00:17.002702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.844928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T13:00:17.00272Z","caller":"traceutil/trace.go:171","msg":"trace[1544232564] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:14342; }","duration":"387.909529ms","start":"2026-07-13T13:00:16.614803Z","end":"2026-07-13T13:00:17.002713Z","steps":["trace[1544232564] 'agreement among raft nodes before linearized reading' (duration: 387.866878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:00:17.002736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:00:16.614784Z","time spent":"387.947501ms","remote":"127.0.0.1:46954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"warn","ts":"2026-07-13T13:00:17.002762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.734457ms","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:5"} {"level":"info","ts":"2026-07-13T13:00:17.002787Z","caller":"traceutil/trace.go:171","msg":"trace[2061271212] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:14342; }","duration":"641.802519ms","start":"2026-07-13T13:00:16.360979Z","end":"2026-07-13T13:00:17.002781Z","steps":["trace[2061271212] 'agreement among raft nodes before linearized reading' (duration: 641.756678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:00:17.002809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:00:16.360945Z","time spent":"641.85483ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"warn","ts":"2026-07-13T13:00:17.002886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.315387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T13:00:17.002904Z","caller":"traceutil/trace.go:171","msg":"trace[371129692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14342; }","duration":"235.390809ms","start":"2026-07-13T13:00:16.767507Z","end":"2026-07-13T13:00:17.002898Z","steps":["trace[371129692] 'agreement among raft nodes before linearized reading' (duration: 235.358299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:00:17.003013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.65221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-13T13:00:17.003065Z","caller":"traceutil/trace.go:171","msg":"trace[2110439752] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:14342; }","duration":"294.758853ms","start":"2026-07-13T13:00:16.708297Z","end":"2026-07-13T13:00:17.003056Z","steps":["trace[2110439752] 'agreement among raft nodes before linearized reading' (duration: 294.587998ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:00:25.102184Z","caller":"traceutil/trace.go:171","msg":"trace[1734619213] linearizableReadLoop","detail":"{readStateIndex:14921; appliedIndex:14920; }","duration":"332.05613ms","start":"2026-07-13T13:00:24.770096Z","end":"2026-07-13T13:00:25.102152Z","steps":["trace[1734619213] 'read index received' (duration: 331.854535ms)","trace[1734619213] 'applied index is now lower than readState.Index' (duration: 200.335µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:00:25.102285Z","caller":"traceutil/trace.go:171","msg":"trace[2024167099] transaction","detail":"{read_only:false; response_revision:14417; number_of_response:1; }","duration":"501.506048ms","start":"2026-07-13T13:00:24.600741Z","end":"2026-07-13T13:00:25.102247Z","steps":["trace[2024167099] 'process raft request' (duration: 501.21761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:00:25.102483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:00:24.60072Z","time spent":"501.650591ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T13:00:25.102551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.275186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T13:00:25.102606Z","caller":"traceutil/trace.go:171","msg":"trace[1742795217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14417; }","duration":"332.507582ms","start":"2026-07-13T13:00:24.770082Z","end":"2026-07-13T13:00:25.10259Z","steps":["trace[1742795217] 'agreement among raft nodes before linearized reading' (duration: 332.245405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:00:25.102705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:00:24.770031Z","time spent":"332.618966ms","remote":"127.0.0.1:52650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-13T13:01:03.796605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.147024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-13T13:01:03.797056Z","caller":"traceutil/trace.go:171","msg":"trace[1113149229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14637; }","duration":"241.650908ms","start":"2026-07-13T13:01:03.555382Z","end":"2026-07-13T13:01:03.797033Z","steps":["trace[1113149229] 'range keys from in-memory index tree' (duration: 240.924708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:01:03.79668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.638967ms","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-07-13T13:01:03.797234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.647789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T13:01:03.797275Z","caller":"traceutil/trace.go:171","msg":"trace[2094377798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14637; }","duration":"366.249674ms","start":"2026-07-13T13:01:03.43096Z","end":"2026-07-13T13:01:03.797209Z","steps":["trace[2094377798] 'range keys from in-memory index tree' (duration: 365.446752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:01:03.796845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.599552ms","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-07-13T13:01:03.797451Z","caller":"traceutil/trace.go:171","msg":"trace[905382508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14637; }","duration":"240.24662ms","start":"2026-07-13T13:01:03.557188Z","end":"2026-07-13T13:01:03.797434Z","steps":["trace[905382508] 'range keys from in-memory index tree' (duration: 239.416557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:01:03.797447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:01:03.430946Z","time spent":"366.435888ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-13T13:01:03.797461Z","caller":"traceutil/trace.go:171","msg":"trace[650592297] transaction","detail":"{read_only:false; response_revision:14638; number_of_response:1; }","duration":"217.463174ms","start":"2026-07-13T13:01:03.579923Z","end":"2026-07-13T13:01:03.797387Z","steps":["trace[650592297] 'process raft request' (duration: 66.491627ms)","trace[650592297] 'compare' (duration: 150.399082ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:01:03.828632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12643} {"level":"info","ts":"2026-07-13T13:01:03.909334Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12643,"took":"77.706349ms","hash":3828867162,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":27332608,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-13T13:01:03.909442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3828867162,"revision":12643,"compact-revision":10473} {"level":"info","ts":"2026-07-13T13:01:13.620306Z","caller":"traceutil/trace.go:171","msg":"trace[268777829] linearizableReadLoop","detail":"{readStateIndex:15326; appliedIndex:15325; }","duration":"259.913062ms","start":"2026-07-13T13:01:13.360362Z","end":"2026-07-13T13:01:13.620275Z","steps":["trace[268777829] 'read index received' (duration: 259.671035ms)","trace[268777829] 'applied index is now lower than readState.Index' (duration: 240.607µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:01:13.620407Z","caller":"traceutil/trace.go:171","msg":"trace[1591124193] transaction","detail":"{read_only:false; response_revision:14808; number_of_response:1; }","duration":"266.322795ms","start":"2026-07-13T13:01:13.354026Z","end":"2026-07-13T13:01:13.620348Z","steps":["trace[1591124193] 'process raft request' (duration: 266.047387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:01:13.62072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.341033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2fe940e6-114b-4063-895c-2f13dbe4124c\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-07-13T13:01:13.620831Z","caller":"traceutil/trace.go:171","msg":"trace[122693899] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2fe940e6-114b-4063-895c-2f13dbe4124c; range_end:; response_count:1; response_revision:14808; }","duration":"260.58512ms","start":"2026-07-13T13:01:13.360217Z","end":"2026-07-13T13:01:13.620802Z","steps":["trace[122693899] 'agreement among raft nodes before linearized reading' (duration: 260.240801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:01:13.620976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.343042ms","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-07-13T13:01:13.621048Z","caller":"traceutil/trace.go:171","msg":"trace[920225912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14808; }","duration":"190.505066ms","start":"2026-07-13T13:01:13.430524Z","end":"2026-07-13T13:01:13.621029Z","steps":["trace[920225912] 'agreement among raft nodes before linearized reading' (duration: 189.989412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:01:13.620987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.700759ms","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-07-13T13:01:13.621175Z","caller":"traceutil/trace.go:171","msg":"trace[1799570925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14808; }","duration":"202.048309ms","start":"2026-07-13T13:01:13.419108Z","end":"2026-07-13T13:01:13.621157Z","steps":["trace[1799570925] 'agreement among raft nodes before linearized reading' (duration: 201.3521ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:02:54.368789Z","caller":"traceutil/trace.go:171","msg":"trace[791970948] linearizableReadLoop","detail":"{readStateIndex:16454; appliedIndex:16453; }","duration":"125.570838ms","start":"2026-07-13T13:02:54.243185Z","end":"2026-07-13T13:02:54.368756Z","steps":["trace[791970948] 'read index received' (duration: 100.253067ms)","trace[791970948] 'applied index is now lower than readState.Index' (duration: 25.316581ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:02:54.368947Z","caller":"traceutil/trace.go:171","msg":"trace[1568941263] transaction","detail":"{read_only:false; response_revision:15901; number_of_response:1; }","duration":"138.999514ms","start":"2026-07-13T13:02:54.229934Z","end":"2026-07-13T13:02:54.368934Z","steps":["trace[1568941263] 'process raft request' (duration: 113.386835ms)","trace[1568941263] 'compare' (duration: 24.997493ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:02:54.369519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.321068ms","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-07-13T13:02:54.36959Z","caller":"traceutil/trace.go:171","msg":"trace[1560437603] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15902; }","duration":"126.41365ms","start":"2026-07-13T13:02:54.243139Z","end":"2026-07-13T13:02:54.369553Z","steps":["trace[1560437603] 'agreement among raft nodes before linearized reading' (duration: 126.231606ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:02:54.369582Z","caller":"traceutil/trace.go:171","msg":"trace[450696555] transaction","detail":"{read_only:false; response_revision:15902; number_of_response:1; }","duration":"111.948817ms","start":"2026-07-13T13:02:54.2576Z","end":"2026-07-13T13:02:54.369548Z","steps":["trace[450696555] 'process raft request' (duration: 111.112975ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:02:54.369737Z","caller":"traceutil/trace.go:171","msg":"trace[971141834] transaction","detail":"{read_only:false; response_revision:15903; number_of_response:1; }","duration":"108.924137ms","start":"2026-07-13T13:02:54.260785Z","end":"2026-07-13T13:02:54.369709Z","steps":["trace[971141834] 'process raft request' (duration: 108.533356ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:02:54.369893Z","caller":"traceutil/trace.go:171","msg":"trace[713739887] transaction","detail":"{read_only:false; response_revision:15904; number_of_response:1; }","duration":"109.05243ms","start":"2026-07-13T13:02:54.260829Z","end":"2026-07-13T13:02:54.369882Z","steps":["trace[713739887] 'process raft request' (duration: 108.823124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:02:54.673637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.017642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-13T13:02:54.673866Z","caller":"traceutil/trace.go:171","msg":"trace[1108543139] linearizableReadLoop","detail":"{readStateIndex:16460; appliedIndex:16459; }","duration":"183.407329ms","start":"2026-07-13T13:02:54.490432Z","end":"2026-07-13T13:02:54.673839Z","steps":["trace[1108543139] 'read index received' (duration: 18.13141ms)","trace[1108543139] 'applied index is now lower than readState.Index' (duration: 165.274019ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:02:54.673959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.529443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T13:02:54.673996Z","caller":"traceutil/trace.go:171","msg":"trace[315164349] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:15907; }","duration":"183.612875ms","start":"2026-07-13T13:02:54.490371Z","end":"2026-07-13T13:02:54.673984Z","steps":["trace[315164349] 'agreement among raft nodes before linearized reading' (duration: 183.531983ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:02:54.674225Z","caller":"traceutil/trace.go:171","msg":"trace[1791646728] transaction","detail":"{read_only:false; response_revision:15907; number_of_response:1; }","duration":"287.309653ms","start":"2026-07-13T13:02:54.386898Z","end":"2026-07-13T13:02:54.674207Z","steps":["trace[1791646728] 'process raft request' (duration: 121.584442ms)","trace[1791646728] 'compare' (duration: 163.79438ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:06:03.836561Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14638} {"level":"info","ts":"2026-07-13T13:06:03.91181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14638,"took":"71.58182ms","hash":700783945,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":34422784,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-13T13:06:03.911872Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":700783945,"revision":14638,"compact-revision":12643} {"level":"info","ts":"2026-07-13T13:08:29.764247Z","caller":"traceutil/trace.go:171","msg":"trace[1830456017] transaction","detail":"{read_only:false; response_revision:18398; number_of_response:1; }","duration":"112.182093ms","start":"2026-07-13T13:08:29.652033Z","end":"2026-07-13T13:08:29.764215Z","steps":["trace[1830456017] 'process raft request' (duration: 112.014198ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:08:29.765243Z","caller":"traceutil/trace.go:171","msg":"trace[767416653] transaction","detail":"{read_only:false; response_revision:18399; number_of_response:1; }","duration":"112.877842ms","start":"2026-07-13T13:08:29.652349Z","end":"2026-07-13T13:08:29.765227Z","steps":["trace[767416653] 'process raft request' (duration: 112.748189ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:08:29.765421Z","caller":"traceutil/trace.go:171","msg":"trace[1388222900] transaction","detail":"{read_only:false; response_revision:18400; number_of_response:1; }","duration":"102.138552ms","start":"2026-07-13T13:08:29.663271Z","end":"2026-07-13T13:08:29.76541Z","steps":["trace[1388222900] 'process raft request' (duration: 101.908145ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:08:29.765675Z","caller":"traceutil/trace.go:171","msg":"trace[34427744] linearizableReadLoop","detail":"{readStateIndex:19036; appliedIndex:19034; }","duration":"101.868003ms","start":"2026-07-13T13:08:29.663785Z","end":"2026-07-13T13:08:29.765653Z","steps":["trace[34427744] 'read index received' (duration: 100.513025ms)","trace[34427744] 'applied index is now lower than readState.Index' (duration: 1.353478ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:08:29.765888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.081299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-xkpz8\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-07-13T13:08:29.765928Z","caller":"traceutil/trace.go:171","msg":"trace[55727208] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-xkpz8; range_end:; response_count:1; response_revision:18401; }","duration":"102.166192ms","start":"2026-07-13T13:08:29.663753Z","end":"2026-07-13T13:08:29.765919Z","steps":["trace[55727208] 'agreement among raft nodes before linearized reading' (duration: 101.995757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:08:37.382498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.514154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-xkq8t\" ","response":"range_response_count:1 size:22089"} {"level":"info","ts":"2026-07-13T13:08:37.382698Z","caller":"traceutil/trace.go:171","msg":"trace[1918471886] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-xkq8t; range_end:; response_count:1; response_revision:18448; }","duration":"318.777891ms","start":"2026-07-13T13:08:37.063899Z","end":"2026-07-13T13:08:37.382677Z","steps":["trace[1918471886] 'range keys from in-memory index tree' (duration: 318.316908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:08:37.382774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:08:37.063877Z","time spent":"318.884883ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22112,"request content":"key:\"/registry/pods/openstack/nova-compute-default-xkq8t\" "} {"level":"warn","ts":"2026-07-13T13:09:22.86336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514806485680957,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-13T13:09:23.002297Z","caller":"traceutil/trace.go:171","msg":"trace[1011885054] linearizableReadLoop","detail":"{readStateIndex:19707; appliedIndex:19706; }","duration":"639.54294ms","start":"2026-07-13T13:09:22.362723Z","end":"2026-07-13T13:09:23.002266Z","steps":["trace[1011885054] 'read index received' (duration: 639.399546ms)","trace[1011885054] 'applied index is now lower than readState.Index' (duration: 142.314µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:09:23.002425Z","caller":"traceutil/trace.go:171","msg":"trace[1918287556] transaction","detail":"{read_only:false; response_revision:19052; number_of_response:1; }","duration":"675.989506ms","start":"2026-07-13T13:09:22.326395Z","end":"2026-07-13T13:09:23.002384Z","steps":["trace[1918287556] 'process raft request' (duration: 675.675797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.002553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.822238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-xkpz8\" ","response":"range_response_count:1 size:9791"} {"level":"warn","ts":"2026-07-13T13:09:23.002583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:09:22.326361Z","time spent":"676.13965ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":21701,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T13:09:23.002602Z","caller":"traceutil/trace.go:171","msg":"trace[1487288020] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-xkpz8; range_end:; response_count:1; response_revision:19052; }","duration":"639.938871ms","start":"2026-07-13T13:09:22.36265Z","end":"2026-07-13T13:09:23.002589Z","steps":["trace[1487288020] 'agreement among raft nodes before linearized reading' (duration: 639.751545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.002647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:09:22.36262Z","time spent":"640.018033ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9814,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-xkpz8\" "} {"level":"warn","ts":"2026-07-13T13:09:23.006366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.761441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:09:23.006436Z","caller":"traceutil/trace.go:171","msg":"trace[406940595] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:19053; }","duration":"637.840543ms","start":"2026-07-13T13:09:22.368578Z","end":"2026-07-13T13:09:23.006418Z","steps":["trace[406940595] 'agreement among raft nodes before linearized reading' (duration: 637.71052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.006476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:09:22.368564Z","time spent":"637.904365ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-07-13T13:09:23.006625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.763129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T13:09:23.006678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.383836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T13:09:23.006684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.393889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T13:09:23.006729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.259346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T13:09:23.006737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.16319ms","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-07-13T13:09:23.006754Z","caller":"traceutil/trace.go:171","msg":"trace[761063851] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:19053; }","duration":"342.301256ms","start":"2026-07-13T13:09:22.664446Z","end":"2026-07-13T13:09:23.006747Z","steps":["trace[761063851] 'agreement among raft nodes before linearized reading' (duration: 342.266195ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:09:23.006761Z","caller":"traceutil/trace.go:171","msg":"trace[1772703542] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:19053; }","duration":"539.229351ms","start":"2026-07-13T13:09:22.467524Z","end":"2026-07-13T13:09:23.006753Z","steps":["trace[1772703542] 'agreement among raft nodes before linearized reading' (duration: 539.19101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.006779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:09:22.467491Z","time spent":"539.282773ms","remote":"127.0.0.1:39338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-07-13T13:09:23.006813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.263162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:09:23.006839Z","caller":"traceutil/trace.go:171","msg":"trace[1620249646] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:19053; }","duration":"252.307443ms","start":"2026-07-13T13:09:22.754525Z","end":"2026-07-13T13:09:23.006832Z","steps":["trace[1620249646] 'agreement among raft nodes before linearized reading' (duration: 252.273752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.006828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.300271ms","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":"warn","ts":"2026-07-13T13:09:23.006884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.592955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T13:09:23.007052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.738429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T13:09:23.007061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.945572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T13:09:23.007034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.63398ms","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-07-13T13:09:23.00708Z","caller":"traceutil/trace.go:171","msg":"trace[2087178675] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:19053; }","duration":"138.822181ms","start":"2026-07-13T13:09:22.868235Z","end":"2026-07-13T13:09:23.007057Z","steps":["trace[2087178675] 'agreement among raft nodes before linearized reading' (duration: 138.597575ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:09:23.007086Z","caller":"traceutil/trace.go:171","msg":"trace[283664742] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:19053; }","duration":"343.998663ms","start":"2026-07-13T13:09:22.663081Z","end":"2026-07-13T13:09:23.007079Z","steps":["trace[283664742] 'agreement among raft nodes before linearized reading' (duration: 343.963682ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:09:23.007081Z","caller":"traceutil/trace.go:171","msg":"trace[1791044969] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:19053; }","duration":"138.80316ms","start":"2026-07-13T13:09:22.868271Z","end":"2026-07-13T13:09:23.007075Z","steps":["trace[1791044969] 'agreement among raft nodes before linearized reading' (duration: 138.760999ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:09:23.006688Z","caller":"traceutil/trace.go:171","msg":"trace[1112631675] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:19053; }","duration":"342.851612ms","start":"2026-07-13T13:09:22.663823Z","end":"2026-07-13T13:09:23.006675Z","steps":["trace[1112631675] 'agreement among raft nodes before linearized reading' (duration: 342.724909ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:09:23.007125Z","caller":"traceutil/trace.go:171","msg":"trace[1161011045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19053; }","duration":"536.758363ms","start":"2026-07-13T13:09:22.470341Z","end":"2026-07-13T13:09:23.0071Z","steps":["trace[1161011045] 'agreement among raft nodes before linearized reading' (duration: 536.361302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.007147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.354034ms","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-07-13T13:09:23.006813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.573105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:09:23.00718Z","caller":"traceutil/trace.go:171","msg":"trace[1106349617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19053; }","duration":"535.403376ms","start":"2026-07-13T13:09:22.471766Z","end":"2026-07-13T13:09:23.007169Z","steps":["trace[1106349617] 'agreement among raft nodes before linearized reading' (duration: 535.291223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.00717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.741045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T13:09:23.007203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:09:22.471755Z","time spent":"535.442757ms","remote":"127.0.0.1:52864","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-07-13T13:09:23.007189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:09:22.470318Z","time spent":"536.856176ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-13T13:09:23.007201Z","caller":"traceutil/trace.go:171","msg":"trace[1322253318] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:19053; }","duration":"343.006766ms","start":"2026-07-13T13:09:22.664178Z","end":"2026-07-13T13:09:23.007185Z","steps":["trace[1322253318] 'agreement among raft nodes before linearized reading' (duration: 342.547094ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:09:23.007239Z","caller":"traceutil/trace.go:171","msg":"trace[244470392] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:19053; }","duration":"253.409814ms","start":"2026-07-13T13:09:22.753791Z","end":"2026-07-13T13:09:23.007201Z","steps":["trace[244470392] 'agreement among raft nodes before linearized reading' (duration: 252.741725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.007249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:09:22.664157Z","time spent":"343.085448ms","remote":"127.0.0.1:52836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"warn","ts":"2026-07-13T13:09:23.006782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:09:22.664435Z","time spent":"342.341318ms","remote":"127.0.0.1:52836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"warn","ts":"2026-07-13T13:09:23.007161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:09:22.663818Z","time spent":"343.334985ms","remote":"127.0.0.1:52836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"info","ts":"2026-07-13T13:09:23.00673Z","caller":"traceutil/trace.go:171","msg":"trace[1080286921] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:19053; }","duration":"138.463441ms","start":"2026-07-13T13:09:22.868255Z","end":"2026-07-13T13:09:23.006718Z","steps":["trace[1080286921] 'agreement among raft nodes before linearized reading' (duration: 138.381329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.00665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.838801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:09:23.00748Z","caller":"traceutil/trace.go:171","msg":"trace[638507821] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:19053; }","duration":"343.689015ms","start":"2026-07-13T13:09:22.663781Z","end":"2026-07-13T13:09:23.00747Z","steps":["trace[638507821] 'agreement among raft nodes before linearized reading' (duration: 342.848792ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:09:23.006699Z","caller":"traceutil/trace.go:171","msg":"trace[608985435] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:19053; }","duration":"343.450569ms","start":"2026-07-13T13:09:22.663241Z","end":"2026-07-13T13:09:23.006691Z","steps":["trace[608985435] 'agreement among raft nodes before linearized reading' (duration: 343.425318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.007525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:09:22.663768Z","time spent":"343.746196ms","remote":"127.0.0.1:52836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"warn","ts":"2026-07-13T13:09:23.007543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:09:22.663214Z","time spent":"344.314382ms","remote":"127.0.0.1:52836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-07-13T13:09:23.006891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.861858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:09:23.007625Z","caller":"traceutil/trace.go:171","msg":"trace[1640081324] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:19053; }","duration":"253.64228ms","start":"2026-07-13T13:09:22.753972Z","end":"2026-07-13T13:09:23.007615Z","steps":["trace[1640081324] 'agreement among raft nodes before linearized reading' (duration: 252.878099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.006884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.502606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:09:23.007675Z","caller":"traceutil/trace.go:171","msg":"trace[559807099] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:19053; }","duration":"254.37122ms","start":"2026-07-13T13:09:22.753291Z","end":"2026-07-13T13:09:23.007662Z","steps":["trace[559807099] 'agreement among raft nodes before linearized reading' (duration: 253.473365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.00694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.93487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:09:23.007749Z","caller":"traceutil/trace.go:171","msg":"trace[68211875] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:19053; }","duration":"253.767094ms","start":"2026-07-13T13:09:22.753971Z","end":"2026-07-13T13:09:23.007738Z","steps":["trace[68211875] 'agreement among raft nodes before linearized reading' (duration: 252.953121ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:09:23.006934Z","caller":"traceutil/trace.go:171","msg":"trace[945248519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19053; }","duration":"286.413285ms","start":"2026-07-13T13:09:22.720494Z","end":"2026-07-13T13:09:23.006908Z","steps":["trace[945248519] 'agreement among raft nodes before linearized reading' (duration: 286.170658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.006929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.65238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:09:23.007885Z","caller":"traceutil/trace.go:171","msg":"trace[1896522031] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:19053; }","duration":"254.624907ms","start":"2026-07-13T13:09:22.753239Z","end":"2026-07-13T13:09:23.007864Z","steps":["trace[1896522031] 'agreement among raft nodes before linearized reading' (duration: 253.577438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.007004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.541453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:09:23.007955Z","caller":"traceutil/trace.go:171","msg":"trace[676991027] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:19053; }","duration":"139.50054ms","start":"2026-07-13T13:09:22.86844Z","end":"2026-07-13T13:09:23.007941Z","steps":["trace[676991027] 'agreement among raft nodes before linearized reading' (duration: 138.549394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.007022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.415636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:09:23.00803Z","caller":"traceutil/trace.go:171","msg":"trace[173453991] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:19053; }","duration":"141.448083ms","start":"2026-07-13T13:09:22.866571Z","end":"2026-07-13T13:09:23.008019Z","steps":["trace[173453991] 'agreement among raft nodes before linearized reading' (duration: 140.425435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:09:23.00703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.707288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T13:09:23.007122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:09:22.663068Z","time spent":"344.047834ms","remote":"127.0.0.1:52836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"info","ts":"2026-07-13T13:09:23.008152Z","caller":"traceutil/trace.go:171","msg":"trace[1457511351] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:19053; }","duration":"139.842849ms","start":"2026-07-13T13:09:22.868303Z","end":"2026-07-13T13:09:23.008145Z","steps":["trace[1457511351] 'agreement among raft nodes before linearized reading' (duration: 138.700987ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:09:56.135007Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fed19d9291630e09","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-13T13:09:56.139882Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-13T13:09:56.139974Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-13T13:11:03.842914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17431} {"level":"info","ts":"2026-07-13T13:11:03.949876Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17431,"took":"103.334522ms","hash":3387243929,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":34668544,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-13T13:11:03.949956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3387243929,"revision":17431,"compact-revision":14638} {"level":"warn","ts":"2026-07-13T13:14:24.666817Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514806485687122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-13T13:14:25.167719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514806485687122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-13T13:14:25.336071Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.494856348s","expected-duration":"1s"} {"level":"info","ts":"2026-07-13T13:14:25.336695Z","caller":"traceutil/trace.go:171","msg":"trace[874411295] linearizableReadLoop","detail":"{readStateIndex:21885; appliedIndex:21884; }","duration":"1.395165222s","start":"2026-07-13T13:14:23.941503Z","end":"2026-07-13T13:14:25.336668Z","steps":["trace[874411295] 'read index received' (duration: 1.395004967s)","trace[874411295] 'applied index is now lower than readState.Index' (duration: 159.435µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:14:25.336804Z","caller":"traceutil/trace.go:171","msg":"trace[2144319902] transaction","detail":"{read_only:false; response_revision:21159; number_of_response:1; }","duration":"1.49567059s","start":"2026-07-13T13:14:23.841111Z","end":"2026-07-13T13:14:25.336782Z","steps":["trace[2144319902] 'process raft request' (duration: 1.495400232s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:14:25.336856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395341446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:14:25.336897Z","caller":"traceutil/trace.go:171","msg":"trace[1605754171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21159; }","duration":"1.39546483s","start":"2026-07-13T13:14:23.94142Z","end":"2026-07-13T13:14:25.336885Z","steps":["trace[1605754171] 'agreement among raft nodes before linearized reading' (duration: 1.395345936s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:14:25.336916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:14:23.841089Z","time spent":"1.495761473s","remote":"127.0.0.1:52864","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-07-13T13:14:25.336968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:14:23.941384Z","time spent":"1.395574943s","remote":"127.0.0.1:52650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-13T13:14:25.339974Z","caller":"traceutil/trace.go:171","msg":"trace[1778102393] transaction","detail":"{read_only:false; response_revision:21160; number_of_response:1; }","duration":"147.851148ms","start":"2026-07-13T13:14:25.192097Z","end":"2026-07-13T13:14:25.339948Z","steps":["trace[1778102393] 'process raft request' (duration: 147.306273ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:14:25.340208Z","caller":"traceutil/trace.go:171","msg":"trace[363564973] transaction","detail":"{read_only:false; response_revision:21161; number_of_response:1; }","duration":"145.341179ms","start":"2026-07-13T13:14:25.194851Z","end":"2026-07-13T13:14:25.340192Z","steps":["trace[363564973] 'process raft request' (duration: 144.66136ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:14:25.340415Z","caller":"traceutil/trace.go:171","msg":"trace[1127678792] transaction","detail":"{read_only:false; response_revision:21162; number_of_response:1; }","duration":"142.209663ms","start":"2026-07-13T13:14:25.198187Z","end":"2026-07-13T13:14:25.340397Z","steps":["trace[1127678792] 'process raft request' (duration: 141.37724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:14:25.340728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.137585ms","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-07-13T13:14:25.340744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.219097ms","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-07-13T13:14:25.34082Z","caller":"traceutil/trace.go:171","msg":"trace[1009371386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21163; }","duration":"673.263018ms","start":"2026-07-13T13:14:24.667536Z","end":"2026-07-13T13:14:25.340799Z","steps":["trace[1009371386] 'agreement among raft nodes before linearized reading' (duration: 672.97216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:14:25.340863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:14:24.66753Z","time spent":"673.32542ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-13T13:14:25.340857Z","caller":"traceutil/trace.go:171","msg":"trace[650331091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21163; }","duration":"673.374932ms","start":"2026-07-13T13:14:24.66746Z","end":"2026-07-13T13:14:25.340835Z","steps":["trace[650331091] 'agreement among raft nodes before linearized reading' (duration: 673.126285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:14:25.340923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:14:24.66744Z","time spent":"673.452073ms","remote":"127.0.0.1:52864","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-07-13T13:14:25.341022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.56743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:14:25.341074Z","caller":"traceutil/trace.go:171","msg":"trace[555825139] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:21163; }","duration":"343.646973ms","start":"2026-07-13T13:14:24.997413Z","end":"2026-07-13T13:14:25.34106Z","steps":["trace[555825139] 'agreement among raft nodes before linearized reading' (duration: 343.566351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:14:25.341094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.627482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T13:14:25.341111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:14:24.997402Z","time spent":"343.699524ms","remote":"127.0.0.1:37372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-13T13:14:25.341126Z","caller":"traceutil/trace.go:171","msg":"trace[233863998] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:21163; }","duration":"356.708944ms","start":"2026-07-13T13:14:24.984405Z","end":"2026-07-13T13:14:25.341114Z","steps":["trace[233863998] 'agreement among raft nodes before linearized reading' (duration: 356.655593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:14:25.341151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:14:24.984385Z","time spent":"356.761155ms","remote":"127.0.0.1:59444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"warn","ts":"2026-07-13T13:14:25.341295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.863506ms","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-07-13T13:14:25.341314Z","caller":"traceutil/trace.go:171","msg":"trace[2111188371] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:21163; }","duration":"634.924538ms","start":"2026-07-13T13:14:24.706383Z","end":"2026-07-13T13:14:25.341308Z","steps":["trace[2111188371] 'agreement among raft nodes before linearized reading' (duration: 634.881197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:14:25.341333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:14:24.706359Z","time spent":"634.969329ms","remote":"127.0.0.1:52822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-13T13:14:25.341459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.863545ms","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-07-13T13:14:25.341512Z","caller":"traceutil/trace.go:171","msg":"trace[22096675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21163; }","duration":"673.933447ms","start":"2026-07-13T13:14:24.667563Z","end":"2026-07-13T13:14:25.341497Z","steps":["trace[22096675] 'agreement among raft nodes before linearized reading' (duration: 673.794143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:14:25.341607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:14:24.667558Z","time spent":"673.985408ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-13T13:14:35.495123Z","caller":"traceutil/trace.go:171","msg":"trace[1204790737] transaction","detail":"{read_only:false; response_revision:21217; number_of_response:1; }","duration":"104.188341ms","start":"2026-07-13T13:14:35.390911Z","end":"2026-07-13T13:14:35.4951Z","steps":["trace[1204790737] 'process raft request' (duration: 104.015046ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:14:35.496107Z","caller":"traceutil/trace.go:171","msg":"trace[216334107] transaction","detail":"{read_only:false; response_revision:21218; number_of_response:1; }","duration":"103.636536ms","start":"2026-07-13T13:14:35.392458Z","end":"2026-07-13T13:14:35.496095Z","steps":["trace[216334107] 'process raft request' (duration: 103.555054ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:14:35.496344Z","caller":"traceutil/trace.go:171","msg":"trace[110020672] transaction","detail":"{read_only:false; response_revision:21219; number_of_response:1; }","duration":"103.809421ms","start":"2026-07-13T13:14:35.392506Z","end":"2026-07-13T13:14:35.496315Z","steps":["trace[110020672] 'process raft request' (duration: 103.563264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:14:56.981372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.241218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-13T13:14:56.981451Z","caller":"traceutil/trace.go:171","msg":"trace[350114176] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:21325; }","duration":"136.372991ms","start":"2026-07-13T13:14:56.845063Z","end":"2026-07-13T13:14:56.981436Z","steps":["trace[350114176] 'range keys from in-memory index tree' (duration: 136.156666ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:14:57.624794Z","caller":"traceutil/trace.go:171","msg":"trace[811236467] linearizableReadLoop","detail":"{readStateIndex:22060; appliedIndex:22059; }","duration":"158.738459ms","start":"2026-07-13T13:14:57.466029Z","end":"2026-07-13T13:14:57.624768Z","steps":["trace[811236467] 'read index received' (duration: 158.563154ms)","trace[811236467] 'applied index is now lower than readState.Index' (duration: 173.485µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:14:57.624845Z","caller":"traceutil/trace.go:171","msg":"trace[758337082] transaction","detail":"{read_only:false; response_revision:21326; number_of_response:1; }","duration":"161.718981ms","start":"2026-07-13T13:14:57.463096Z","end":"2026-07-13T13:14:57.624815Z","steps":["trace[758337082] 'process raft request' (duration: 161.415923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:14:57.624986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.920534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-13T13:14:57.625034Z","caller":"traceutil/trace.go:171","msg":"trace[1091344025] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:21326; }","duration":"159.026797ms","start":"2026-07-13T13:14:57.465993Z","end":"2026-07-13T13:14:57.62502Z","steps":["trace[1091344025] 'agreement among raft nodes before linearized reading' (duration: 158.911094ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:14:57.638701Z","caller":"traceutil/trace.go:171","msg":"trace[1406034943] transaction","detail":"{read_only:false; response_revision:21327; number_of_response:1; }","duration":"158.957745ms","start":"2026-07-13T13:14:57.479725Z","end":"2026-07-13T13:14:57.638682Z","steps":["trace[1406034943] 'process raft request' (duration: 158.737809ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:14:57.639169Z","caller":"traceutil/trace.go:171","msg":"trace[986304763] transaction","detail":"{read_only:false; response_revision:21328; number_of_response:1; }","duration":"157.413432ms","start":"2026-07-13T13:14:57.481744Z","end":"2026-07-13T13:14:57.639157Z","steps":["trace[986304763] 'process raft request' (duration: 156.849986ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:14:57.784926Z","caller":"traceutil/trace.go:171","msg":"trace[1259477401] transaction","detail":"{read_only:false; response_revision:21330; number_of_response:1; }","duration":"141.586345ms","start":"2026-07-13T13:14:57.643306Z","end":"2026-07-13T13:14:57.784892Z","steps":["trace[1259477401] 'process raft request' (duration: 129.414668ms)","trace[1259477401] 'compare' (duration: 11.860078ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:14:57.78589Z","caller":"traceutil/trace.go:171","msg":"trace[100465058] transaction","detail":"{read_only:false; response_revision:21331; number_of_response:1; }","duration":"142.496431ms","start":"2026-07-13T13:14:57.643372Z","end":"2026-07-13T13:14:57.785868Z","steps":["trace[100465058] 'process raft request' (duration: 142.311826ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:14:57.786098Z","caller":"traceutil/trace.go:171","msg":"trace[58207366] transaction","detail":"{read_only:false; response_revision:21332; number_of_response:1; }","duration":"142.706796ms","start":"2026-07-13T13:14:57.643382Z","end":"2026-07-13T13:14:57.786089Z","steps":["trace[58207366] 'process raft request' (duration: 142.438669ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:14:57.786448Z","caller":"traceutil/trace.go:171","msg":"trace[1546989393] transaction","detail":"{read_only:false; response_revision:21333; number_of_response:1; }","duration":"142.634214ms","start":"2026-07-13T13:14:57.643775Z","end":"2026-07-13T13:14:57.786409Z","steps":["trace[1546989393] 'process raft request' (duration: 142.273324ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:16:03.849766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19793} {"level":"info","ts":"2026-07-13T13:16:03.967778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19793,"took":"114.819594ms","hash":3392285585,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":30339072,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-13T13:16:03.967844Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3392285585,"revision":19793,"compact-revision":17431} {"level":"info","ts":"2026-07-13T13:18:52.455664Z","caller":"traceutil/trace.go:171","msg":"trace[1311177719] transaction","detail":"{read_only:false; response_revision:23789; number_of_response:1; }","duration":"240.104861ms","start":"2026-07-13T13:18:52.215519Z","end":"2026-07-13T13:18:52.455624Z","steps":["trace[1311177719] 'process raft request' (duration: 211.024733ms)","trace[1311177719] 'compare' (duration: 28.516103ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:21:03.857671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21991} {"level":"info","ts":"2026-07-13T13:21:03.96724Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21991,"took":"104.289126ms","hash":1052630825,"current-db-size-bytes":58068992,"current-db-size":"58 MB","current-db-size-in-use-bytes":44863488,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-07-13T13:21:03.967323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1052630825,"revision":21991,"compact-revision":19793} {"level":"info","ts":"2026-07-13T13:21:49.435357Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006719.wal"} {"level":"info","ts":"2026-07-13T13:22:48.342006Z","caller":"traceutil/trace.go:171","msg":"trace[96999750] transaction","detail":"{read_only:false; response_revision:26137; number_of_response:1; }","duration":"101.808426ms","start":"2026-07-13T13:22:48.240168Z","end":"2026-07-13T13:22:48.341977Z","steps":["trace[96999750] 'process raft request' (duration: 100.763457ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:50.198073Z","caller":"traceutil/trace.go:171","msg":"trace[169744025] transaction","detail":"{read_only:false; response_revision:26155; number_of_response:1; }","duration":"197.616945ms","start":"2026-07-13T13:22:50.000429Z","end":"2026-07-13T13:22:50.198046Z","steps":["trace[169744025] 'process raft request' (duration: 197.447541ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:50.695515Z","caller":"traceutil/trace.go:171","msg":"trace[707447233] linearizableReadLoop","detail":"{readStateIndex:27026; appliedIndex:27025; }","duration":"210.00315ms","start":"2026-07-13T13:22:50.485472Z","end":"2026-07-13T13:22:50.695475Z","steps":["trace[707447233] 'read index received' (duration: 120.399572ms)","trace[707447233] 'applied index is now lower than readState.Index' (duration: 89.602138ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:22:50.695875Z","caller":"traceutil/trace.go:171","msg":"trace[686729390] transaction","detail":"{read_only:false; response_revision:26157; number_of_response:1; }","duration":"243.179021ms","start":"2026-07-13T13:22:50.452653Z","end":"2026-07-13T13:22:50.695832Z","steps":["trace[686729390] 'process raft request' (duration: 153.311806ms)","trace[686729390] 'compare' (duration: 89.33065ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:22:50.696161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.679721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:22:50.696288Z","caller":"traceutil/trace.go:171","msg":"trace[1847932969] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26157; }","duration":"133.857686ms","start":"2026-07-13T13:22:50.562411Z","end":"2026-07-13T13:22:50.696269Z","steps":["trace[1847932969] 'agreement among raft nodes before linearized reading' (duration: 133.63821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:22:50.696303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.649959ms","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-07-13T13:22:50.6964Z","caller":"traceutil/trace.go:171","msg":"trace[1018440879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26157; }","duration":"205.766323ms","start":"2026-07-13T13:22:50.490607Z","end":"2026-07-13T13:22:50.696373Z","steps":["trace[1018440879] 'agreement among raft nodes before linearized reading' (duration: 205.444104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:22:50.696582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.212558ms","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-07-13T13:22:50.696789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.288856ms","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-07-13T13:22:50.696845Z","caller":"traceutil/trace.go:171","msg":"trace[1772450366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26157; }","duration":"211.395399ms","start":"2026-07-13T13:22:50.485436Z","end":"2026-07-13T13:22:50.696832Z","steps":["trace[1772450366] 'agreement among raft nodes before linearized reading' (duration: 210.507704ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:50.696924Z","caller":"traceutil/trace.go:171","msg":"trace[309023240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26157; }","duration":"205.595828ms","start":"2026-07-13T13:22:50.491304Z","end":"2026-07-13T13:22:50.6969Z","steps":["trace[309023240] 'agreement among raft nodes before linearized reading' (duration: 204.792626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:22:53.69485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.347075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T13:22:53.695091Z","caller":"traceutil/trace.go:171","msg":"trace[1202938345] transaction","detail":"{read_only:false; response_revision:26184; number_of_response:1; }","duration":"147.941736ms","start":"2026-07-13T13:22:53.547132Z","end":"2026-07-13T13:22:53.695073Z","steps":["trace[1202938345] 'process raft request' (duration: 147.832593ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:53.6952Z","caller":"traceutil/trace.go:171","msg":"trace[968579593] transaction","detail":"{read_only:false; response_revision:26183; number_of_response:1; }","duration":"203.433437ms","start":"2026-07-13T13:22:53.491722Z","end":"2026-07-13T13:22:53.695155Z","steps":["trace[968579593] 'process raft request' (duration: 71.69179ms)","trace[968579593] 'compare' (duration: 131.14135ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:22:54.178973Z","caller":"traceutil/trace.go:171","msg":"trace[1444211984] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"161.189535ms","start":"2026-07-13T13:22:54.017751Z","end":"2026-07-13T13:22:54.178941Z","steps":["trace[1444211984] 'process raft request' (duration: 160.948928ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:54.178945Z","caller":"traceutil/trace.go:171","msg":"trace[2135420580] linearizableReadLoop","detail":"{readStateIndex:27055; appliedIndex:27055; }","duration":"148.999437ms","start":"2026-07-13T13:22:54.029901Z","end":"2026-07-13T13:22:54.1789Z","steps":["trace[2135420580] 'read index received' (duration: 148.981436ms)","trace[2135420580] 'applied index is now lower than readState.Index' (duration: 14.351µs)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:22:54.179118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.181502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:22:54.179215Z","caller":"traceutil/trace.go:171","msg":"trace[1152808125] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:26185; }","duration":"149.351287ms","start":"2026-07-13T13:22:54.029841Z","end":"2026-07-13T13:22:54.179192Z","steps":["trace[1152808125] 'agreement among raft nodes before linearized reading' (duration: 149.145041ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:54.351988Z","caller":"traceutil/trace.go:171","msg":"trace[1983354623] linearizableReadLoop","detail":"{readStateIndex:27056; appliedIndex:27055; }","duration":"121.071011ms","start":"2026-07-13T13:22:54.230888Z","end":"2026-07-13T13:22:54.351959Z","steps":["trace[1983354623] 'read index received' (duration: 119.780625ms)","trace[1983354623] 'applied index is now lower than readState.Index' (duration: 1.288656ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:22:54.352283Z","caller":"traceutil/trace.go:171","msg":"trace[1797586310] transaction","detail":"{read_only:false; response_revision:26186; number_of_response:1; }","duration":"131.865301ms","start":"2026-07-13T13:22:54.220399Z","end":"2026-07-13T13:22:54.352264Z","steps":["trace[1797586310] 'process raft request' (duration: 130.236895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:22:54.35249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.557987ms","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-07-13T13:22:54.352543Z","caller":"traceutil/trace.go:171","msg":"trace[836170142] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:26186; }","duration":"120.687281ms","start":"2026-07-13T13:22:54.23184Z","end":"2026-07-13T13:22:54.352527Z","steps":["trace[836170142] 'agreement among raft nodes before linearized reading' (duration: 120.564178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:22:54.352873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.983176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-13T13:22:54.352902Z","caller":"traceutil/trace.go:171","msg":"trace[451132472] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:26186; }","duration":"122.061939ms","start":"2026-07-13T13:22:54.230833Z","end":"2026-07-13T13:22:54.352895Z","steps":["trace[451132472] 'agreement among raft nodes before linearized reading' (duration: 121.880944ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:59.312352Z","caller":"traceutil/trace.go:171","msg":"trace[937107360] linearizableReadLoop","detail":"{readStateIndex:27158; appliedIndex:27157; }","duration":"118.681395ms","start":"2026-07-13T13:22:59.193644Z","end":"2026-07-13T13:22:59.312325Z","steps":["trace[937107360] 'read index received' (duration: 118.598563ms)","trace[937107360] 'applied index is now lower than readState.Index' (duration: 81.512µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:22:59.312423Z","caller":"traceutil/trace.go:171","msg":"trace[450944093] transaction","detail":"{read_only:false; response_revision:26287; number_of_response:1; }","duration":"127.969892ms","start":"2026-07-13T13:22:59.184435Z","end":"2026-07-13T13:22:59.312405Z","steps":["trace[450944093] 'process raft request' (duration: 127.738736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:22:59.312569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.906291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-07-13T13:22:59.312615Z","caller":"traceutil/trace.go:171","msg":"trace[1456144253] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:26287; }","duration":"118.999424ms","start":"2026-07-13T13:22:59.193602Z","end":"2026-07-13T13:22:59.312602Z","steps":["trace[1456144253] 'agreement among raft nodes before linearized reading' (duration: 118.838899ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:59.416286Z","caller":"traceutil/trace.go:171","msg":"trace[621199477] linearizableReadLoop","detail":"{readStateIndex:27159; appliedIndex:27158; }","duration":"100.446899ms","start":"2026-07-13T13:22:59.315794Z","end":"2026-07-13T13:22:59.416241Z","steps":["trace[621199477] 'read index received' (duration: 100.389457ms)","trace[621199477] 'applied index is now lower than readState.Index' (duration: 55.932µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:22:59.41635Z","caller":"traceutil/trace.go:171","msg":"trace[2016686803] transaction","detail":"{read_only:false; response_revision:26288; number_of_response:1; }","duration":"114.835958ms","start":"2026-07-13T13:22:59.301472Z","end":"2026-07-13T13:22:59.416308Z","steps":["trace[2016686803] 'process raft request' (duration: 114.399056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:22:59.416538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.734326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2ae9fb1a-f16a-4e1d-9778-775ecc545427\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-07-13T13:22:59.416587Z","caller":"traceutil/trace.go:171","msg":"trace[239935338] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2ae9fb1a-f16a-4e1d-9778-775ecc545427; range_end:; response_count:1; response_revision:26288; }","duration":"100.85813ms","start":"2026-07-13T13:22:59.315715Z","end":"2026-07-13T13:22:59.416573Z","steps":["trace[239935338] 'agreement among raft nodes before linearized reading' (duration: 100.653875ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:59.499825Z","caller":"traceutil/trace.go:171","msg":"trace[1022741492] transaction","detail":"{read_only:false; response_revision:26289; number_of_response:1; }","duration":"140.637175ms","start":"2026-07-13T13:22:59.359159Z","end":"2026-07-13T13:22:59.499796Z","steps":["trace[1022741492] 'process raft request' (duration: 140.435509ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:59.500149Z","caller":"traceutil/trace.go:171","msg":"trace[1099553146] transaction","detail":"{read_only:false; response_revision:26291; number_of_response:1; }","duration":"138.108075ms","start":"2026-07-13T13:22:59.362016Z","end":"2026-07-13T13:22:59.500124Z","steps":["trace[1099553146] 'process raft request' (duration: 137.986672ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:59.500175Z","caller":"traceutil/trace.go:171","msg":"trace[47260169] transaction","detail":"{read_only:false; response_revision:26290; number_of_response:1; }","duration":"139.620987ms","start":"2026-07-13T13:22:59.360526Z","end":"2026-07-13T13:22:59.500147Z","steps":["trace[47260169] 'process raft request' (duration: 139.221776ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:59.532997Z","caller":"traceutil/trace.go:171","msg":"trace[82218313] transaction","detail":"{read_only:false; response_revision:26292; number_of_response:1; }","duration":"133.558609ms","start":"2026-07-13T13:22:59.399402Z","end":"2026-07-13T13:22:59.53296Z","steps":["trace[82218313] 'process raft request' (duration: 100.708857ms)","trace[82218313] 'compare' (duration: 32.690857ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:22:59.90471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.742262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e7594bbd-f062-47d0-85b2-6f74c41d4fda\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-07-13T13:22:59.904811Z","caller":"traceutil/trace.go:171","msg":"trace[607207741] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e7594bbd-f062-47d0-85b2-6f74c41d4fda; range_end:; response_count:1; response_revision:26292; }","duration":"259.909206ms","start":"2026-07-13T13:22:59.644879Z","end":"2026-07-13T13:22:59.904788Z","steps":["trace[607207741] 'range keys from in-memory index tree' (duration: 259.488845ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:59.905123Z","caller":"traceutil/trace.go:171","msg":"trace[1245714235] linearizableReadLoop","detail":"{readStateIndex:27164; appliedIndex:27163; }","duration":"133.733153ms","start":"2026-07-13T13:22:59.771362Z","end":"2026-07-13T13:22:59.905095Z","steps":["trace[1245714235] 'read index received' (duration: 132.761506ms)","trace[1245714235] 'applied index is now lower than readState.Index' (duration: 970.197µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:22:59.905172Z","caller":"traceutil/trace.go:171","msg":"trace[1558736699] transaction","detail":"{read_only:false; response_revision:26293; number_of_response:1; }","duration":"185.645875ms","start":"2026-07-13T13:22:59.71947Z","end":"2026-07-13T13:22:59.905116Z","steps":["trace[1558736699] 'process raft request' (duration: 184.637447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:22:59.905345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.952049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:22:59.905479Z","caller":"traceutil/trace.go:171","msg":"trace[808863881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26293; }","duration":"134.126504ms","start":"2026-07-13T13:22:59.771325Z","end":"2026-07-13T13:22:59.905451Z","steps":["trace[808863881] 'agreement among raft nodes before linearized reading' (duration: 133.867467ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:22:59.922162Z","caller":"traceutil/trace.go:171","msg":"trace[118041156] transaction","detail":"{read_only:false; response_revision:26294; number_of_response:1; }","duration":"138.284089ms","start":"2026-07-13T13:22:59.783846Z","end":"2026-07-13T13:22:59.92213Z","steps":["trace[118041156] 'process raft request' (duration: 138.010401ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:00.493555Z","caller":"traceutil/trace.go:171","msg":"trace[570642675] transaction","detail":"{read_only:false; response_revision:26295; number_of_response:1; }","duration":"115.101316ms","start":"2026-07-13T13:23:00.378421Z","end":"2026-07-13T13:23:00.493522Z","steps":["trace[570642675] 'process raft request' (duration: 114.840868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:00.78637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.115877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T13:23:00.78651Z","caller":"traceutil/trace.go:171","msg":"trace[780461210] linearizableReadLoop","detail":"{readStateIndex:27167; appliedIndex:27166; }","duration":"277.554106ms","start":"2026-07-13T13:23:00.508928Z","end":"2026-07-13T13:23:00.786482Z","steps":["trace[780461210] 'read index received' (duration: 118.270024ms)","trace[780461210] 'applied index is now lower than readState.Index' (duration: 159.282182ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:00.786625Z","caller":"traceutil/trace.go:171","msg":"trace[620140983] transaction","detail":"{read_only:false; response_revision:26296; number_of_response:1; }","duration":"288.52169ms","start":"2026-07-13T13:23:00.498091Z","end":"2026-07-13T13:23:00.786613Z","steps":["trace[620140983] 'process raft request' (duration: 129.070973ms)","trace[620140983] 'compare' (duration: 158.983094ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:23:00.78679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.816472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:23:00.786878Z","caller":"traceutil/trace.go:171","msg":"trace[795735791] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:26296; }","duration":"139.920835ms","start":"2026-07-13T13:23:00.646937Z","end":"2026-07-13T13:23:00.786858Z","steps":["trace[795735791] 'agreement among raft nodes before linearized reading' (duration: 139.76359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:00.786957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.986048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-13T13:23:00.787084Z","caller":"traceutil/trace.go:171","msg":"trace[732501481] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:26296; }","duration":"278.203574ms","start":"2026-07-13T13:23:00.508855Z","end":"2026-07-13T13:23:00.787058Z","steps":["trace[732501481] 'agreement among raft nodes before linearized reading' (duration: 277.839384ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:01.027182Z","caller":"traceutil/trace.go:171","msg":"trace[209527346] transaction","detail":"{read_only:false; response_revision:26297; number_of_response:1; }","duration":"235.685373ms","start":"2026-07-13T13:23:00.791396Z","end":"2026-07-13T13:23:01.027082Z","steps":["trace[209527346] 'process raft request' (duration: 148.709288ms)","trace[209527346] 'compare' (duration: 86.691757ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:01.029827Z","caller":"traceutil/trace.go:171","msg":"trace[297984661] transaction","detail":"{read_only:false; response_revision:26298; number_of_response:1; }","duration":"237.014469ms","start":"2026-07-13T13:23:00.792784Z","end":"2026-07-13T13:23:01.029798Z","steps":["trace[297984661] 'process raft request' (duration: 236.756122ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:01.029825Z","caller":"traceutil/trace.go:171","msg":"trace[898213899] transaction","detail":"{read_only:false; response_revision:26299; number_of_response:1; }","duration":"235.740244ms","start":"2026-07-13T13:23:00.79406Z","end":"2026-07-13T13:23:01.0298Z","steps":["trace[898213899] 'process raft request' (duration: 235.658452ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:01.030026Z","caller":"traceutil/trace.go:171","msg":"trace[420202447] linearizableReadLoop","detail":"{readStateIndex:27171; appliedIndex:27167; }","duration":"120.221948ms","start":"2026-07-13T13:23:00.909784Z","end":"2026-07-13T13:23:01.030006Z","steps":["trace[420202447] 'read index received' (duration: 30.421715ms)","trace[420202447] 'applied index is now lower than readState.Index' (duration: 89.798523ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:01.030148Z","caller":"traceutil/trace.go:171","msg":"trace[790287555] transaction","detail":"{read_only:false; response_revision:26300; number_of_response:1; }","duration":"233.944984ms","start":"2026-07-13T13:23:00.796075Z","end":"2026-07-13T13:23:01.03002Z","steps":["trace[790287555] 'process raft request' (duration: 233.687107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:01.030358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.553797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e7594bbd-f062-47d0-85b2-6f74c41d4fda\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-07-13T13:23:01.030405Z","caller":"traceutil/trace.go:171","msg":"trace[1137907283] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e7594bbd-f062-47d0-85b2-6f74c41d4fda; range_end:; response_count:1; response_revision:26300; }","duration":"120.682101ms","start":"2026-07-13T13:23:00.90971Z","end":"2026-07-13T13:23:01.030392Z","steps":["trace[1137907283] 'agreement among raft nodes before linearized reading' (duration: 120.364572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:01.608722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.371185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T13:23:01.608969Z","caller":"traceutil/trace.go:171","msg":"trace[2104929837] transaction","detail":"{read_only:false; response_revision:26302; number_of_response:1; }","duration":"187.191907ms","start":"2026-07-13T13:23:01.421747Z","end":"2026-07-13T13:23:01.608939Z","steps":["trace[2104929837] 'process raft request' (duration: 187.097314ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:01.608973Z","caller":"traceutil/trace.go:171","msg":"trace[547490150] transaction","detail":"{read_only:false; response_revision:26301; number_of_response:1; }","duration":"311.092367ms","start":"2026-07-13T13:23:01.297832Z","end":"2026-07-13T13:23:01.608924Z","steps":["trace[547490150] 'process raft request' (duration: 26.393542ms)","trace[547490150] 'compare' (duration: 284.218531ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:23:01.609141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:01.297759Z","time spent":"311.285832ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T13:23:01.64246Z","caller":"traceutil/trace.go:171","msg":"trace[1511543051] transaction","detail":"{read_only:false; response_revision:26306; number_of_response:1; }","duration":"104.978264ms","start":"2026-07-13T13:23:01.537464Z","end":"2026-07-13T13:23:01.642442Z","steps":["trace[1511543051] 'process raft request' (duration: 104.938023ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:01.642849Z","caller":"traceutil/trace.go:171","msg":"trace[1224965616] transaction","detail":"{read_only:false; response_revision:26303; number_of_response:1; }","duration":"137.685433ms","start":"2026-07-13T13:23:01.505151Z","end":"2026-07-13T13:23:01.642837Z","steps":["trace[1224965616] 'process raft request' (duration: 137.043325ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:01.642992Z","caller":"traceutil/trace.go:171","msg":"trace[200829812] transaction","detail":"{read_only:false; response_revision:26304; number_of_response:1; }","duration":"135.702188ms","start":"2026-07-13T13:23:01.50728Z","end":"2026-07-13T13:23:01.642982Z","steps":["trace[200829812] 'process raft request' (duration: 135.0377ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:01.643146Z","caller":"traceutil/trace.go:171","msg":"trace[479830822] transaction","detail":"{read_only:false; response_revision:26305; number_of_response:1; }","duration":"135.720299ms","start":"2026-07-13T13:23:01.507391Z","end":"2026-07-13T13:23:01.643111Z","steps":["trace[479830822] 'process raft request' (duration: 134.977178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:03.39033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.601491ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:23:03.390476Z","caller":"traceutil/trace.go:171","msg":"trace[2019497171] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26317; }","duration":"195.770855ms","start":"2026-07-13T13:23:03.194685Z","end":"2026-07-13T13:23:03.390456Z","steps":["trace[2019497171] 'range keys from in-memory index tree' (duration: 195.57423ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:03.796158Z","caller":"traceutil/trace.go:171","msg":"trace[877263374] transaction","detail":"{read_only:false; response_revision:26320; number_of_response:1; }","duration":"147.305449ms","start":"2026-07-13T13:23:03.648835Z","end":"2026-07-13T13:23:03.79614Z","steps":["trace[877263374] 'process raft request' (duration: 147.208237ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:03.79616Z","caller":"traceutil/trace.go:171","msg":"trace[2108404719] transaction","detail":"{read_only:false; response_revision:26319; number_of_response:1; }","duration":"147.568697ms","start":"2026-07-13T13:23:03.648549Z","end":"2026-07-13T13:23:03.796118Z","steps":["trace[2108404719] 'process raft request' (duration: 147.184696ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:03.796379Z","caller":"traceutil/trace.go:171","msg":"trace[412230276] transaction","detail":"{read_only:false; response_revision:26321; number_of_response:1; }","duration":"147.474604ms","start":"2026-07-13T13:23:03.648897Z","end":"2026-07-13T13:23:03.796372Z","steps":["trace[412230276] 'process raft request' (duration: 147.210346ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:03.796491Z","caller":"traceutil/trace.go:171","msg":"trace[688380504] transaction","detail":"{read_only:false; response_revision:26322; number_of_response:1; }","duration":"145.943912ms","start":"2026-07-13T13:23:03.650541Z","end":"2026-07-13T13:23:03.796485Z","steps":["trace[688380504] 'process raft request' (duration: 145.640973ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:08.219686Z","caller":"traceutil/trace.go:171","msg":"trace[6063835] transaction","detail":"{read_only:false; response_revision:26375; number_of_response:1; }","duration":"189.848251ms","start":"2026-07-13T13:23:08.029807Z","end":"2026-07-13T13:23:08.219655Z","steps":["trace[6063835] 'process raft request' (duration: 189.601204ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:08.252387Z","caller":"traceutil/trace.go:171","msg":"trace[1091966457] linearizableReadLoop","detail":"{readStateIndex:27250; appliedIndex:27248; }","duration":"145.763306ms","start":"2026-07-13T13:23:08.106593Z","end":"2026-07-13T13:23:08.252357Z","steps":["trace[1091966457] 'read index received' (duration: 112.932285ms)","trace[1091966457] 'applied index is now lower than readState.Index' (duration: 32.829411ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:23:08.252708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.091066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-07-13T13:23:08.252797Z","caller":"traceutil/trace.go:171","msg":"trace[588426930] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26376; }","duration":"146.23927ms","start":"2026-07-13T13:23:08.106541Z","end":"2026-07-13T13:23:08.25278Z","steps":["trace[588426930] 'agreement among raft nodes before linearized reading' (duration: 145.964312ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:08.25325Z","caller":"traceutil/trace.go:171","msg":"trace[1984470562] transaction","detail":"{read_only:false; response_revision:26376; number_of_response:1; }","duration":"222.475167ms","start":"2026-07-13T13:23:08.02997Z","end":"2026-07-13T13:23:08.252445Z","steps":["trace[1984470562] 'process raft request' (duration: 222.22221ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:08.547701Z","caller":"traceutil/trace.go:171","msg":"trace[1344672910] transaction","detail":"{read_only:false; response_revision:26377; number_of_response:1; }","duration":"282.78517ms","start":"2026-07-13T13:23:08.264894Z","end":"2026-07-13T13:23:08.547679Z","steps":["trace[1344672910] 'process raft request' (duration: 282.626076ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:08.825873Z","caller":"traceutil/trace.go:171","msg":"trace[797224371] transaction","detail":"{read_only:false; response_revision:26378; number_of_response:1; }","duration":"309.296408ms","start":"2026-07-13T13:23:08.516554Z","end":"2026-07-13T13:23:08.82585Z","steps":["trace[797224371] 'process raft request' (duration: 309.100142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:08.826032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:08.516534Z","time spent":"309.414221ms","remote":"127.0.0.1:52864","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-07-13T13:23:09.028716Z","caller":"traceutil/trace.go:171","msg":"trace[709719511] transaction","detail":"{read_only:false; response_revision:26379; number_of_response:1; }","duration":"472.936261ms","start":"2026-07-13T13:23:08.555748Z","end":"2026-07-13T13:23:09.028684Z","steps":["trace[709719511] 'process raft request' (duration: 472.495159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:09.02889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:08.555727Z","time spent":"473.071004ms","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T13:23:09.029125Z","caller":"traceutil/trace.go:171","msg":"trace[783612993] transaction","detail":"{read_only:false; response_revision:26380; number_of_response:1; }","duration":"473.213728ms","start":"2026-07-13T13:23:08.555881Z","end":"2026-07-13T13:23:09.029095Z","steps":["trace[783612993] 'process raft request' (duration: 472.657103ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.029211Z","caller":"traceutil/trace.go:171","msg":"trace[680135708] transaction","detail":"{read_only:false; response_revision:26382; number_of_response:1; }","duration":"472.655703ms","start":"2026-07-13T13:23:08.556532Z","end":"2026-07-13T13:23:09.029188Z","steps":["trace[680135708] 'process raft request' (duration: 472.260932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:09.029264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:08.555868Z","time spent":"473.321901ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T13:23:09.02922Z","caller":"traceutil/trace.go:171","msg":"trace[1233257056] linearizableReadLoop","detail":"{readStateIndex:27256; appliedIndex:27251; }","duration":"466.017479ms","start":"2026-07-13T13:23:08.563168Z","end":"2026-07-13T13:23:09.029185Z","steps":["trace[1233257056] 'read index received' (duration: 262.595351ms)","trace[1233257056] 'applied index is now lower than readState.Index' (duration: 203.377786ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:23:09.029293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:08.556502Z","time spent":"472.753226ms","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T13:23:09.029388Z","caller":"traceutil/trace.go:171","msg":"trace[1418465177] transaction","detail":"{read_only:false; response_revision:26381; number_of_response:1; }","duration":"473.229229ms","start":"2026-07-13T13:23:08.556117Z","end":"2026-07-13T13:23:09.029346Z","steps":["trace[1418465177] 'process raft request' (duration: 472.600812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:09.029589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.389069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10651"} {"level":"warn","ts":"2026-07-13T13:23:09.029651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.739101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:23:09.031551Z","caller":"traceutil/trace.go:171","msg":"trace[608526505] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26382; }","duration":"468.383004ms","start":"2026-07-13T13:23:08.563126Z","end":"2026-07-13T13:23:09.031509Z","steps":["trace[608526505] 'agreement among raft nodes before linearized reading' (duration: 466.309157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:09.031354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:08.556111Z","time spent":"475.156753ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T13:23:09.031678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:08.563116Z","time spent":"468.516948ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10674,"request content":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-07-13T13:23:09.031618Z","caller":"traceutil/trace.go:171","msg":"trace[1644138893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26382; }","duration":"261.729606ms","start":"2026-07-13T13:23:08.769861Z","end":"2026-07-13T13:23:09.031591Z","steps":["trace[1644138893] 'agreement among raft nodes before linearized reading' (duration: 259.751512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:09.029697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.081232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-13T13:23:09.029662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.341234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-13T13:23:09.032118Z","caller":"traceutil/trace.go:171","msg":"trace[1280615474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26382; }","duration":"210.831093ms","start":"2026-07-13T13:23:08.82127Z","end":"2026-07-13T13:23:09.032101Z","steps":["trace[1280615474] 'agreement among raft nodes before linearized reading' (duration: 208.237021ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.03221Z","caller":"traceutil/trace.go:171","msg":"trace[319892285] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26382; }","duration":"297.468378ms","start":"2026-07-13T13:23:08.734506Z","end":"2026-07-13T13:23:09.031974Z","steps":["trace[319892285] 'agreement among raft nodes before linearized reading' (duration: 295.034101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:09.303192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.866671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T13:23:09.303425Z","caller":"traceutil/trace.go:171","msg":"trace[1314367745] transaction","detail":"{read_only:false; response_revision:26384; number_of_response:1; }","duration":"146.288212ms","start":"2026-07-13T13:23:09.1571Z","end":"2026-07-13T13:23:09.303388Z","steps":["trace[1314367745] 'process raft request' (duration: 146.212859ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.303496Z","caller":"traceutil/trace.go:171","msg":"trace[1993812417] transaction","detail":"{read_only:false; response_revision:26383; number_of_response:1; }","duration":"267.364103ms","start":"2026-07-13T13:23:09.036108Z","end":"2026-07-13T13:23:09.303472Z","steps":["trace[1993812417] 'process raft request' (duration: 126.118221ms)","trace[1993812417] 'compare' (duration: 140.559843ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:09.303544Z","caller":"traceutil/trace.go:171","msg":"trace[1940759476] linearizableReadLoop","detail":"{readStateIndex:27257; appliedIndex:27256; }","duration":"241.882215ms","start":"2026-07-13T13:23:09.061627Z","end":"2026-07-13T13:23:09.303509Z","steps":["trace[1940759476] 'read index received' (duration: 100.618313ms)","trace[1940759476] 'applied index is now lower than readState.Index' (duration: 141.260222ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:23:09.303889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.242985ms","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-07-13T13:23:09.303945Z","caller":"traceutil/trace.go:171","msg":"trace[104152068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26384; }","duration":"242.388459ms","start":"2026-07-13T13:23:09.061538Z","end":"2026-07-13T13:23:09.303927Z","steps":["trace[104152068] 'agreement among raft nodes before linearized reading' (duration: 242.150583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:09.303985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.038859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-13T13:23:09.304027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.145173ms","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-07-13T13:23:09.304077Z","caller":"traceutil/trace.go:171","msg":"trace[1917507784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26384; }","duration":"233.259696ms","start":"2026-07-13T13:23:09.070806Z","end":"2026-07-13T13:23:09.304065Z","steps":["trace[1917507784] 'agreement among raft nodes before linearized reading' (duration: 233.115792ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.30408Z","caller":"traceutil/trace.go:171","msg":"trace[699866160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26384; }","duration":"242.166093ms","start":"2026-07-13T13:23:09.061895Z","end":"2026-07-13T13:23:09.304061Z","steps":["trace[699866160] 'agreement among raft nodes before linearized reading' (duration: 241.881715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:09.30404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.578897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-13T13:23:09.30414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.509601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-678d86df5d-z8w2r\" ","response":"range_response_count:1 size:8865"} {"level":"info","ts":"2026-07-13T13:23:09.304181Z","caller":"traceutil/trace.go:171","msg":"trace[723823433] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-678d86df5d-z8w2r; range_end:; response_count:1; response_revision:26384; }","duration":"149.595053ms","start":"2026-07-13T13:23:09.154573Z","end":"2026-07-13T13:23:09.304168Z","steps":["trace[723823433] 'agreement among raft nodes before linearized reading' (duration: 149.457369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:09.30411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.59124ms","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":"warn","ts":"2026-07-13T13:23:09.304005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.800254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-cluster-api-proxy-b8gz6.18c1dc1bb7de8b60\" ","response":"range_response_count:1 size:914"} {"level":"info","ts":"2026-07-13T13:23:09.304402Z","caller":"traceutil/trace.go:171","msg":"trace[37390371] range","detail":"{range_begin:/registry/events/openstack/magnum-cluster-api-proxy-b8gz6.18c1dc1bb7de8b60; range_end:; response_count:1; response_revision:26384; }","duration":"148.238565ms","start":"2026-07-13T13:23:09.156149Z","end":"2026-07-13T13:23:09.304387Z","steps":["trace[37390371] 'agreement among raft nodes before linearized reading' (duration: 147.658839ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.30438Z","caller":"traceutil/trace.go:171","msg":"trace[1323139828] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26384; }","duration":"119.825227ms","start":"2026-07-13T13:23:09.184472Z","end":"2026-07-13T13:23:09.304297Z","steps":["trace[1323139828] 'agreement among raft nodes before linearized reading' (duration: 119.58071ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.304218Z","caller":"traceutil/trace.go:171","msg":"trace[107396620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26384; }","duration":"232.785793ms","start":"2026-07-13T13:23:09.071376Z","end":"2026-07-13T13:23:09.304162Z","steps":["trace[107396620] 'agreement among raft nodes before linearized reading' (duration: 232.454814ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.424826Z","caller":"traceutil/trace.go:171","msg":"trace[946463004] transaction","detail":"{read_only:false; response_revision:26385; number_of_response:1; }","duration":"116.114404ms","start":"2026-07-13T13:23:09.308686Z","end":"2026-07-13T13:23:09.4248Z","steps":["trace[946463004] 'process raft request' (duration: 115.649111ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.500945Z","caller":"traceutil/trace.go:171","msg":"trace[863244161] transaction","detail":"{read_only:false; response_revision:26386; number_of_response:1; }","duration":"192.185847ms","start":"2026-07-13T13:23:09.308717Z","end":"2026-07-13T13:23:09.500903Z","steps":["trace[863244161] 'process raft request' (duration: 191.886209ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.501126Z","caller":"traceutil/trace.go:171","msg":"trace[1361284862] transaction","detail":"{read_only:false; response_revision:26387; number_of_response:1; }","duration":"192.378512ms","start":"2026-07-13T13:23:09.30873Z","end":"2026-07-13T13:23:09.501109Z","steps":["trace[1361284862] 'process raft request' (duration: 192.028312ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.5014Z","caller":"traceutil/trace.go:171","msg":"trace[80362854] linearizableReadLoop","detail":"{readStateIndex:27261; appliedIndex:27258; }","duration":"192.462474ms","start":"2026-07-13T13:23:09.308927Z","end":"2026-07-13T13:23:09.501389Z","steps":["trace[80362854] 'read index received' (duration: 115.332352ms)","trace[80362854] 'applied index is now lower than readState.Index' (duration: 77.128922ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:09.501464Z","caller":"traceutil/trace.go:171","msg":"trace[1508224050] transaction","detail":"{read_only:false; response_revision:26390; number_of_response:1; }","duration":"186.722165ms","start":"2026-07-13T13:23:09.314735Z","end":"2026-07-13T13:23:09.501457Z","steps":["trace[1508224050] 'process raft request' (duration: 186.553461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:09.501606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.66288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e7594bbd-f062-47d0-85b2-6f74c41d4fda\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-13T13:23:09.50161Z","caller":"traceutil/trace.go:171","msg":"trace[1611502343] transaction","detail":"{read_only:false; response_revision:26388; number_of_response:1; }","duration":"192.31836ms","start":"2026-07-13T13:23:09.309277Z","end":"2026-07-13T13:23:09.501595Z","steps":["trace[1611502343] 'process raft request' (duration: 191.782585ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.501643Z","caller":"traceutil/trace.go:171","msg":"trace[1147449619] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e7594bbd-f062-47d0-85b2-6f74c41d4fda; range_end:; response_count:1; response_revision:26391; }","duration":"192.743112ms","start":"2026-07-13T13:23:09.308887Z","end":"2026-07-13T13:23:09.50163Z","steps":["trace[1147449619] 'agreement among raft nodes before linearized reading' (duration: 192.575877ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.501642Z","caller":"traceutil/trace.go:171","msg":"trace[161164031] transaction","detail":"{read_only:false; response_revision:26389; number_of_response:1; }","duration":"189.898163ms","start":"2026-07-13T13:23:09.311724Z","end":"2026-07-13T13:23:09.501622Z","steps":["trace[161164031] 'process raft request' (duration: 189.529233ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.501739Z","caller":"traceutil/trace.go:171","msg":"trace[2046763443] transaction","detail":"{read_only:false; response_revision:26391; number_of_response:1; }","duration":"174.589548ms","start":"2026-07-13T13:23:09.327138Z","end":"2026-07-13T13:23:09.501727Z","steps":["trace[2046763443] 'process raft request' (duration: 174.219477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:09.501782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.846847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18431"} {"level":"info","ts":"2026-07-13T13:23:09.501845Z","caller":"traceutil/trace.go:171","msg":"trace[323924486] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26391; }","duration":"191.95604ms","start":"2026-07-13T13:23:09.309876Z","end":"2026-07-13T13:23:09.501832Z","steps":["trace[323924486] 'agreement among raft nodes before linearized reading' (duration: 191.747554ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.842305Z","caller":"traceutil/trace.go:171","msg":"trace[1645098102] transaction","detail":"{read_only:false; response_revision:26393; number_of_response:1; }","duration":"158.004517ms","start":"2026-07-13T13:23:09.68427Z","end":"2026-07-13T13:23:09.842275Z","steps":["trace[1645098102] 'process raft request' (duration: 156.199897ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.956365Z","caller":"traceutil/trace.go:171","msg":"trace[753063960] transaction","detail":"{read_only:false; response_revision:26394; number_of_response:1; }","duration":"137.677752ms","start":"2026-07-13T13:23:09.818638Z","end":"2026-07-13T13:23:09.956316Z","steps":["trace[753063960] 'process raft request' (duration: 136.991343ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.9564Z","caller":"traceutil/trace.go:171","msg":"trace[210990591] transaction","detail":"{read_only:false; response_revision:26396; number_of_response:1; }","duration":"137.623441ms","start":"2026-07-13T13:23:09.818749Z","end":"2026-07-13T13:23:09.956372Z","steps":["trace[210990591] 'process raft request' (duration: 137.533678ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.95648Z","caller":"traceutil/trace.go:171","msg":"trace[1374464162] transaction","detail":"{read_only:false; response_revision:26397; number_of_response:1; }","duration":"137.101126ms","start":"2026-07-13T13:23:09.819368Z","end":"2026-07-13T13:23:09.956469Z","steps":["trace[1374464162] 'process raft request' (duration: 136.963803ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:09.956501Z","caller":"traceutil/trace.go:171","msg":"trace[267388558] linearizableReadLoop","detail":"{readStateIndex:27271; appliedIndex:27267; }","duration":"114.282962ms","start":"2026-07-13T13:23:09.842188Z","end":"2026-07-13T13:23:09.95647Z","steps":["trace[267388558] 'read index received' (duration: 113.380357ms)","trace[267388558] 'applied index is now lower than readState.Index' (duration: 901.075µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:09.956382Z","caller":"traceutil/trace.go:171","msg":"trace[1827476514] transaction","detail":"{read_only:false; response_revision:26395; number_of_response:1; }","duration":"137.703043ms","start":"2026-07-13T13:23:09.818646Z","end":"2026-07-13T13:23:09.956349Z","steps":["trace[1827476514] 'process raft request' (duration: 137.571169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:09.956705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.627216ms","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-07-13T13:23:09.956753Z","caller":"traceutil/trace.go:171","msg":"trace[1368636127] 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:26397; }","duration":"117.77034ms","start":"2026-07-13T13:23:09.838971Z","end":"2026-07-13T13:23:09.956741Z","steps":["trace[1368636127] 'agreement among raft nodes before linearized reading' (duration: 117.602005ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:12.758043Z","caller":"traceutil/trace.go:171","msg":"trace[1052998930] linearizableReadLoop","detail":"{readStateIndex:27299; appliedIndex:27298; }","duration":"126.405129ms","start":"2026-07-13T13:23:12.631609Z","end":"2026-07-13T13:23:12.758014Z","steps":["trace[1052998930] 'read index received' (duration: 126.189063ms)","trace[1052998930] 'applied index is now lower than readState.Index' (duration: 214.656µs)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:23:12.758268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.641696ms","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-07-13T13:23:12.758299Z","caller":"traceutil/trace.go:171","msg":"trace[364677931] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26424; }","duration":"126.734618ms","start":"2026-07-13T13:23:12.631556Z","end":"2026-07-13T13:23:12.758291Z","steps":["trace[364677931] 'agreement among raft nodes before linearized reading' (duration: 126.547263ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:12.758374Z","caller":"traceutil/trace.go:171","msg":"trace[1962904169] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"128.291702ms","start":"2026-07-13T13:23:12.630038Z","end":"2026-07-13T13:23:12.758329Z","steps":["trace[1962904169] 'process raft request' (duration: 127.787288ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:12.965191Z","caller":"traceutil/trace.go:171","msg":"trace[174731512] linearizableReadLoop","detail":"{readStateIndex:27301; appliedIndex:27300; }","duration":"118.619083ms","start":"2026-07-13T13:23:12.846543Z","end":"2026-07-13T13:23:12.965163Z","steps":["trace[174731512] 'read index received' (duration: 110.93631ms)","trace[174731512] 'applied index is now lower than readState.Index' (duration: 7.681713ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:12.965467Z","caller":"traceutil/trace.go:171","msg":"trace[306025128] transaction","detail":"{read_only:false; response_revision:26426; number_of_response:1; }","duration":"120.804443ms","start":"2026-07-13T13:23:12.844654Z","end":"2026-07-13T13:23:12.965459Z","steps":["trace[306025128] 'process raft request' (duration: 112.967206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:12.966462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.790039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18c1dc24b7dc719d\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-07-13T13:23:12.966508Z","caller":"traceutil/trace.go:171","msg":"trace[658657271] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18c1dc24b7dc719d; range_end:; response_count:1; response_revision:26426; }","duration":"113.870851ms","start":"2026-07-13T13:23:12.852625Z","end":"2026-07-13T13:23:12.966496Z","steps":["trace[658657271] 'agreement among raft nodes before linearized reading' (duration: 113.667316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:12.966663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.123425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:23:12.966686Z","caller":"traceutil/trace.go:171","msg":"trace[241662150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26426; }","duration":"120.206777ms","start":"2026-07-13T13:23:12.846472Z","end":"2026-07-13T13:23:12.966679Z","steps":["trace[241662150] 'agreement among raft nodes before linearized reading' (duration: 120.166546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:27.16886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.66535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T13:23:27.168971Z","caller":"traceutil/trace.go:171","msg":"trace[1540014633] transaction","detail":"{read_only:false; response_revision:26552; number_of_response:1; }","duration":"272.925377ms","start":"2026-07-13T13:23:26.896021Z","end":"2026-07-13T13:23:27.168947Z","steps":["trace[1540014633] 'process raft request' (duration: 139.101932ms)","trace[1540014633] 'compare' (duration: 133.542517ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:27.169567Z","caller":"traceutil/trace.go:171","msg":"trace[182462262] transaction","detail":"{read_only:false; response_revision:26554; number_of_response:1; }","duration":"147.037632ms","start":"2026-07-13T13:23:27.022477Z","end":"2026-07-13T13:23:27.169515Z","steps":["trace[182462262] 'process raft request' (duration: 146.98132ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:27.169579Z","caller":"traceutil/trace.go:171","msg":"trace[779243339] transaction","detail":"{read_only:false; response_revision:26553; number_of_response:1; }","duration":"273.232165ms","start":"2026-07-13T13:23:26.896302Z","end":"2026-07-13T13:23:27.169535Z","steps":["trace[779243339] 'process raft request' (duration: 273.016579ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:27.612521Z","caller":"traceutil/trace.go:171","msg":"trace[1698821153] linearizableReadLoop","detail":"{readStateIndex:27433; appliedIndex:27432; }","duration":"218.347941ms","start":"2026-07-13T13:23:27.394153Z","end":"2026-07-13T13:23:27.612501Z","steps":["trace[1698821153] 'read index received' (duration: 218.210828ms)","trace[1698821153] 'applied index is now lower than readState.Index' (duration: 136.483µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:27.612571Z","caller":"traceutil/trace.go:171","msg":"trace[2112083133] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"221.554201ms","start":"2026-07-13T13:23:27.390997Z","end":"2026-07-13T13:23:27.612551Z","steps":["trace[2112083133] 'process raft request' (duration: 221.393516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:27.612681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.515487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-13T13:23:27.6127Z","caller":"traceutil/trace.go:171","msg":"trace[1318092554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26555; }","duration":"218.574968ms","start":"2026-07-13T13:23:27.39412Z","end":"2026-07-13T13:23:27.612695Z","steps":["trace[1318092554] 'agreement among raft nodes before linearized reading' (duration: 218.473335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:27.612797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.255562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:23:27.61286Z","caller":"traceutil/trace.go:171","msg":"trace[805860444] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:26555; }","duration":"199.342114ms","start":"2026-07-13T13:23:27.413502Z","end":"2026-07-13T13:23:27.612844Z","steps":["trace[805860444] 'agreement among raft nodes before linearized reading' (duration: 199.20264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:27.612871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.209861ms","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-07-13T13:23:27.612921Z","caller":"traceutil/trace.go:171","msg":"trace[1052309927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26555; }","duration":"105.301234ms","start":"2026-07-13T13:23:27.507606Z","end":"2026-07-13T13:23:27.612907Z","steps":["trace[1052309927] 'agreement among raft nodes before linearized reading' (duration: 105.130609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:27.613058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.273619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-07-13T13:23:27.613151Z","caller":"traceutil/trace.go:171","msg":"trace[1778372467] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26555; }","duration":"129.422083ms","start":"2026-07-13T13:23:27.483708Z","end":"2026-07-13T13:23:27.61313Z","steps":["trace[1778372467] 'agreement among raft nodes before linearized reading' (duration: 129.142305ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:27.730063Z","caller":"traceutil/trace.go:171","msg":"trace[309803448] transaction","detail":"{read_only:false; response_revision:26556; number_of_response:1; }","duration":"113.701377ms","start":"2026-07-13T13:23:27.616338Z","end":"2026-07-13T13:23:27.730039Z","steps":["trace[309803448] 'process raft request' (duration: 112.999807ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:28.023022Z","caller":"traceutil/trace.go:171","msg":"trace[333199477] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"405.969061ms","start":"2026-07-13T13:23:27.617033Z","end":"2026-07-13T13:23:28.023002Z","steps":["trace[333199477] 'process raft request' (duration: 405.778816ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:28.023071Z","caller":"traceutil/trace.go:171","msg":"trace[1020710649] transaction","detail":"{read_only:false; response_revision:26558; number_of_response:1; }","duration":"405.979772ms","start":"2026-07-13T13:23:27.617072Z","end":"2026-07-13T13:23:28.023052Z","steps":["trace[1020710649] 'process raft request' (duration: 405.863908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:28.023148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:27.617008Z","time spent":"406.070613ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T13:23:28.023181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:27.617058Z","time spent":"406.064854ms","remote":"127.0.0.1:52864","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-07-13T13:23:28.023233Z","caller":"traceutil/trace.go:171","msg":"trace[943554281] linearizableReadLoop","detail":"{readStateIndex:27439; appliedIndex:27433; }","duration":"350.47297ms","start":"2026-07-13T13:23:27.672743Z","end":"2026-07-13T13:23:28.023216Z","steps":["trace[943554281] 'read index received' (duration: 56.612642ms)","trace[943554281] 'applied index is now lower than readState.Index' (duration: 293.859328ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:28.023278Z","caller":"traceutil/trace.go:171","msg":"trace[1061572321] transaction","detail":"{read_only:false; response_revision:26559; number_of_response:1; }","duration":"406.138966ms","start":"2026-07-13T13:23:27.617134Z","end":"2026-07-13T13:23:28.023273Z","steps":["trace[1061572321] 'process raft request' (duration: 405.831807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:28.023306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:27.617125Z","time spent":"406.164896ms","remote":"127.0.0.1:52864","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-07-13T13:23:28.023398Z","caller":"traceutil/trace.go:171","msg":"trace[2145395283] transaction","detail":"{read_only:false; response_revision:26561; number_of_response:1; }","duration":"404.163291ms","start":"2026-07-13T13:23:27.619227Z","end":"2026-07-13T13:23:28.02339Z","steps":["trace[2145395283] 'process raft request' (duration: 403.77839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:28.023447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:27.619214Z","time spent":"404.209342ms","remote":"127.0.0.1:37438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3290,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T13:23:28.023469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.717417ms","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:6"} {"level":"info","ts":"2026-07-13T13:23:28.023498Z","caller":"traceutil/trace.go:171","msg":"trace[130967231] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:26561; }","duration":"350.790829ms","start":"2026-07-13T13:23:27.672698Z","end":"2026-07-13T13:23:28.023489Z","steps":["trace[130967231] 'agreement among raft nodes before linearized reading' (duration: 350.725117ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:28.023515Z","caller":"traceutil/trace.go:171","msg":"trace[496248868] transaction","detail":"{read_only:false; response_revision:26560; number_of_response:1; }","duration":"405.514148ms","start":"2026-07-13T13:23:27.617993Z","end":"2026-07-13T13:23:28.023507Z","steps":["trace[496248868] 'process raft request' (duration: 404.991314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:28.023534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:27.672686Z","time spent":"350.83823ms","remote":"127.0.0.1:33842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-07-13T13:23:28.023544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:27.617981Z","time spent":"405.546839ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T13:23:28.04634Z","caller":"traceutil/trace.go:171","msg":"trace[150486936] transaction","detail":"{read_only:false; response_revision:26562; number_of_response:1; }","duration":"138.617958ms","start":"2026-07-13T13:23:27.907701Z","end":"2026-07-13T13:23:28.046318Z","steps":["trace[150486936] 'process raft request' (duration: 138.094764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:28.046433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.274968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:23:28.046509Z","caller":"traceutil/trace.go:171","msg":"trace[149452060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26564; }","duration":"277.402191ms","start":"2026-07-13T13:23:27.76909Z","end":"2026-07-13T13:23:28.046492Z","steps":["trace[149452060] 'agreement among raft nodes before linearized reading' (duration: 277.259807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:28.046564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.526383ms","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-07-13T13:23:28.046662Z","caller":"traceutil/trace.go:171","msg":"trace[448075609] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:26564; }","duration":"251.652236ms","start":"2026-07-13T13:23:27.794984Z","end":"2026-07-13T13:23:28.046636Z","steps":["trace[448075609] 'agreement among raft nodes before linearized reading' (duration: 251.489442ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:28.213086Z","caller":"traceutil/trace.go:171","msg":"trace[1700177816] transaction","detail":"{read_only:false; response_revision:26565; number_of_response:1; }","duration":"188.081092ms","start":"2026-07-13T13:23:28.024983Z","end":"2026-07-13T13:23:28.213064Z","steps":["trace[1700177816] 'process raft request' (duration: 187.870276ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:28.213277Z","caller":"traceutil/trace.go:171","msg":"trace[1238721507] linearizableReadLoop","detail":"{readStateIndex:27444; appliedIndex:27442; }","duration":"167.11644ms","start":"2026-07-13T13:23:28.046121Z","end":"2026-07-13T13:23:28.213237Z","steps":["trace[1238721507] 'read index received' (duration: 166.674977ms)","trace[1238721507] 'applied index is now lower than readState.Index' (duration: 439.623µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:28.213385Z","caller":"traceutil/trace.go:171","msg":"trace[742673775] transaction","detail":"{read_only:false; response_revision:26566; number_of_response:1; }","duration":"183.448522ms","start":"2026-07-13T13:23:28.029926Z","end":"2026-07-13T13:23:28.213375Z","steps":["trace[742673775] 'process raft request' (duration: 183.104423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:28.213442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.723741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:23:28.213479Z","caller":"traceutil/trace.go:171","msg":"trace[802993708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26566; }","duration":"165.800893ms","start":"2026-07-13T13:23:28.047669Z","end":"2026-07-13T13:23:28.21347Z","steps":["trace[802993708] 'agreement among raft nodes before linearized reading' (duration: 165.733331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:28.213676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.506856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-07-13T13:23:28.213759Z","caller":"traceutil/trace.go:171","msg":"trace[141987251] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26566; }","duration":"178.616979ms","start":"2026-07-13T13:23:28.035123Z","end":"2026-07-13T13:23:28.21374Z","steps":["trace[141987251] 'agreement among raft nodes before linearized reading' (duration: 178.382892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:28.514684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.461031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T13:23:28.515042Z","caller":"traceutil/trace.go:171","msg":"trace[827780039] transaction","detail":"{read_only:false; response_revision:26568; number_of_response:1; }","duration":"191.93567ms","start":"2026-07-13T13:23:28.323092Z","end":"2026-07-13T13:23:28.515028Z","steps":["trace[827780039] 'process raft request' (duration: 191.675732ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:28.515047Z","caller":"traceutil/trace.go:171","msg":"trace[1813308936] transaction","detail":"{read_only:false; response_revision:26567; number_of_response:1; }","duration":"222.870638ms","start":"2026-07-13T13:23:28.292161Z","end":"2026-07-13T13:23:28.515031Z","steps":["trace[1813308936] 'process raft request' (duration: 41.991976ms)","trace[1813308936] 'compare' (duration: 180.148661ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:23:29.372214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.702984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:23:29.372307Z","caller":"traceutil/trace.go:171","msg":"trace[1545252721] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:26569; }","duration":"150.852708ms","start":"2026-07-13T13:23:29.221434Z","end":"2026-07-13T13:23:29.372286Z","steps":["trace[1545252721] 'count revisions from in-memory index tree' (duration: 150.620242ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:29.758373Z","caller":"traceutil/trace.go:171","msg":"trace[1742828535] transaction","detail":"{read_only:false; response_revision:26570; number_of_response:1; }","duration":"230.310424ms","start":"2026-07-13T13:23:29.528035Z","end":"2026-07-13T13:23:29.758345Z","steps":["trace[1742828535] 'process raft request' (duration: 230.14599ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:29.811549Z","caller":"traceutil/trace.go:171","msg":"trace[845442137] transaction","detail":"{read_only:false; response_revision:26571; number_of_response:1; }","duration":"282.356139ms","start":"2026-07-13T13:23:29.529163Z","end":"2026-07-13T13:23:29.811519Z","steps":["trace[845442137] 'process raft request' (duration: 282.113942ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:45.274826Z","caller":"traceutil/trace.go:171","msg":"trace[84971544] transaction","detail":"{read_only:false; response_revision:26695; number_of_response:1; }","duration":"106.740813ms","start":"2026-07-13T13:23:45.168046Z","end":"2026-07-13T13:23:45.274787Z","steps":["trace[84971544] 'process raft request' (duration: 106.433715ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:56.39226Z","caller":"traceutil/trace.go:171","msg":"trace[1086538849] transaction","detail":"{read_only:false; response_revision:26777; number_of_response:1; }","duration":"193.500696ms","start":"2026-07-13T13:23:56.198738Z","end":"2026-07-13T13:23:56.392238Z","steps":["trace[1086538849] 'process raft request' (duration: 181.457874ms)","trace[1086538849] 'compare' (duration: 11.760365ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:56.392312Z","caller":"traceutil/trace.go:171","msg":"trace[76697649] transaction","detail":"{read_only:false; response_revision:26778; number_of_response:1; }","duration":"191.023368ms","start":"2026-07-13T13:23:56.201277Z","end":"2026-07-13T13:23:56.3923Z","steps":["trace[76697649] 'process raft request' (duration: 190.919405ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:56.392451Z","caller":"traceutil/trace.go:171","msg":"trace[1495346562] transaction","detail":"{read_only:false; response_revision:26779; number_of_response:1; }","duration":"191.033738ms","start":"2026-07-13T13:23:56.201412Z","end":"2026-07-13T13:23:56.392445Z","steps":["trace[1495346562] 'process raft request' (duration: 190.857913ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:56.392458Z","caller":"traceutil/trace.go:171","msg":"trace[1061264421] linearizableReadLoop","detail":"{readStateIndex:27663; appliedIndex:27660; }","duration":"182.609186ms","start":"2026-07-13T13:23:56.209822Z","end":"2026-07-13T13:23:56.392432Z","steps":["trace[1061264421] 'read index received' (duration: 170.357318ms)","trace[1061264421] 'applied index is now lower than readState.Index' (duration: 12.251018ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:23:56.392577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.739699ms","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-07-13T13:23:56.392602Z","caller":"traceutil/trace.go:171","msg":"trace[391773940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26779; }","duration":"182.815821ms","start":"2026-07-13T13:23:56.209782Z","end":"2026-07-13T13:23:56.392598Z","steps":["trace[391773940] 'agreement among raft nodes before linearized reading' (duration: 182.713468ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:56.744063Z","caller":"traceutil/trace.go:171","msg":"trace[721934373] transaction","detail":"{read_only:false; response_revision:26781; number_of_response:1; }","duration":"425.178795ms","start":"2026-07-13T13:23:56.318872Z","end":"2026-07-13T13:23:56.744051Z","steps":["trace[721934373] 'process raft request' (duration: 424.974729ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:56.744225Z","caller":"traceutil/trace.go:171","msg":"trace[276048291] linearizableReadLoop","detail":"{readStateIndex:27666; appliedIndex:27663; }","duration":"351.719149ms","start":"2026-07-13T13:23:56.392485Z","end":"2026-07-13T13:23:56.744204Z","steps":["trace[276048291] 'read index received' (duration: 351.189845ms)","trace[276048291] 'applied index is now lower than readState.Index' (duration: 528.364µs)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:23:56.74424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:56.318839Z","time spent":"425.306429ms","remote":"127.0.0.1:52864","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-07-13T13:23:56.744253Z","caller":"traceutil/trace.go:171","msg":"trace[365808818] transaction","detail":"{read_only:false; response_revision:26782; number_of_response:1; }","duration":"425.254087ms","start":"2026-07-13T13:23:56.318976Z","end":"2026-07-13T13:23:56.74423Z","steps":["trace[365808818] 'process raft request' (duration: 425.01816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:56.744391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:56.31894Z","time spent":"425.3722ms","remote":"127.0.0.1:52864","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-07-13T13:23:56.744421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.13295ms","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-07-13T13:23:56.74445Z","caller":"traceutil/trace.go:171","msg":"trace[1803544989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26782; }","duration":"534.187951ms","start":"2026-07-13T13:23:56.210252Z","end":"2026-07-13T13:23:56.74444Z","steps":["trace[1803544989] 'agreement among raft nodes before linearized reading' (duration: 534.034846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:56.744481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:56.21024Z","time spent":"534.234653ms","remote":"127.0.0.1:52864","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-07-13T13:23:56.744525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.065313ms","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-07-13T13:23:56.744613Z","caller":"traceutil/trace.go:171","msg":"trace[1466747034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26782; }","duration":"507.206187ms","start":"2026-07-13T13:23:56.237386Z","end":"2026-07-13T13:23:56.744592Z","steps":["trace[1466747034] 'agreement among raft nodes before linearized reading' (duration: 506.932849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:56.74467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:56.237367Z","time spent":"507.294069ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-13T13:23:56.744619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.32596ms","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-07-13T13:23:56.74474Z","caller":"traceutil/trace.go:171","msg":"trace[842750613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26782; }","duration":"507.457844ms","start":"2026-07-13T13:23:56.23727Z","end":"2026-07-13T13:23:56.744728Z","steps":["trace[842750613] 'agreement among raft nodes before linearized reading' (duration: 507.237308ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:56.744759Z","caller":"traceutil/trace.go:171","msg":"trace[746071937] transaction","detail":"{read_only:false; response_revision:26780; number_of_response:1; }","duration":"529.664146ms","start":"2026-07-13T13:23:56.214259Z","end":"2026-07-13T13:23:56.743924Z","steps":["trace[746071937] 'process raft request' (duration: 529.41497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:56.744816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:56.214249Z","time spent":"530.5411ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4935,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T13:23:56.744825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:56.237264Z","time spent":"507.535616ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-13T13:23:56.822898Z","caller":"traceutil/trace.go:171","msg":"trace[1376684284] transaction","detail":"{read_only:false; response_revision:26783; number_of_response:1; }","duration":"418.410888ms","start":"2026-07-13T13:23:56.404455Z","end":"2026-07-13T13:23:56.822866Z","steps":["trace[1376684284] 'process raft request' (duration: 382.305723ms)","trace[1376684284] 'compare' (duration: 35.878589ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:23:56.823095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:23:56.404436Z","time spent":"418.543901ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T13:23:56.82316Z","caller":"traceutil/trace.go:171","msg":"trace[1339497655] transaction","detail":"{read_only:false; response_revision:26784; number_of_response:1; }","duration":"248.76922ms","start":"2026-07-13T13:23:56.574367Z","end":"2026-07-13T13:23:56.823136Z","steps":["trace[1339497655] 'process raft request' (duration: 248.440881ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:56.823223Z","caller":"traceutil/trace.go:171","msg":"trace[1914237585] transaction","detail":"{read_only:false; response_revision:26785; number_of_response:1; }","duration":"204.913901ms","start":"2026-07-13T13:23:56.618303Z","end":"2026-07-13T13:23:56.823217Z","steps":["trace[1914237585] 'process raft request' (duration: 204.707515ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:56.823171Z","caller":"traceutil/trace.go:171","msg":"trace[229371240] transaction","detail":"{read_only:false; response_revision:26786; number_of_response:1; }","duration":"203.633455ms","start":"2026-07-13T13:23:56.619515Z","end":"2026-07-13T13:23:56.823148Z","steps":["trace[229371240] 'process raft request' (duration: 203.589594ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:56.864316Z","caller":"traceutil/trace.go:171","msg":"trace[1231324853] transaction","detail":"{read_only:false; response_revision:26787; number_of_response:1; }","duration":"115.042933ms","start":"2026-07-13T13:23:56.749254Z","end":"2026-07-13T13:23:56.864296Z","steps":["trace[1231324853] 'process raft request' (duration: 114.832317ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:56.864315Z","caller":"traceutil/trace.go:171","msg":"trace[454208557] transaction","detail":"{read_only:false; response_revision:26788; number_of_response:1; }","duration":"114.282041ms","start":"2026-07-13T13:23:56.750012Z","end":"2026-07-13T13:23:56.864294Z","steps":["trace[454208557] 'process raft request' (duration: 114.198229ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:56.86464Z","caller":"traceutil/trace.go:171","msg":"trace[486143735] transaction","detail":"{read_only:false; response_revision:26789; number_of_response:1; }","duration":"114.471447ms","start":"2026-07-13T13:23:56.750154Z","end":"2026-07-13T13:23:56.864625Z","steps":["trace[486143735] 'process raft request' (duration: 114.097346ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:56.864727Z","caller":"traceutil/trace.go:171","msg":"trace[208333404] transaction","detail":"{read_only:false; response_revision:26790; number_of_response:1; }","duration":"112.0382ms","start":"2026-07-13T13:23:56.752662Z","end":"2026-07-13T13:23:56.8647Z","steps":["trace[208333404] 'process raft request' (duration: 111.850474ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:57.015253Z","caller":"traceutil/trace.go:171","msg":"trace[2090976102] linearizableReadLoop","detail":"{readStateIndex:27675; appliedIndex:27674; }","duration":"148.854235ms","start":"2026-07-13T13:23:56.866367Z","end":"2026-07-13T13:23:57.015221Z","steps":["trace[2090976102] 'read index received' (duration: 72.746756ms)","trace[2090976102] 'applied index is now lower than readState.Index' (duration: 76.106179ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:23:57.015357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.970328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:23:57.01538Z","caller":"traceutil/trace.go:171","msg":"trace[682293021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26790; }","duration":"149.01961ms","start":"2026-07-13T13:23:56.866352Z","end":"2026-07-13T13:23:57.015372Z","steps":["trace[682293021] 'agreement among raft nodes before linearized reading' (duration: 148.953708ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:58.436161Z","caller":"traceutil/trace.go:171","msg":"trace[891747347] transaction","detail":"{read_only:false; response_revision:26794; number_of_response:1; }","duration":"192.242731ms","start":"2026-07-13T13:23:58.243887Z","end":"2026-07-13T13:23:58.43613Z","steps":["trace[891747347] 'process raft request' (duration: 106.831376ms)","trace[891747347] 'compare' (duration: 84.899611ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:58.650248Z","caller":"traceutil/trace.go:171","msg":"trace[1649389550] transaction","detail":"{read_only:false; response_revision:26797; number_of_response:1; }","duration":"249.247673ms","start":"2026-07-13T13:23:58.400983Z","end":"2026-07-13T13:23:58.650231Z","steps":["trace[1649389550] 'process raft request' (duration: 249.223223ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:58.650491Z","caller":"traceutil/trace.go:171","msg":"trace[867938005] transaction","detail":"{read_only:false; response_revision:26795; number_of_response:1; }","duration":"251.101104ms","start":"2026-07-13T13:23:58.399373Z","end":"2026-07-13T13:23:58.650471Z","steps":["trace[867938005] 'process raft request' (duration: 171.847289ms)","trace[867938005] 'compare' (duration: 78.685659ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:58.650568Z","caller":"traceutil/trace.go:171","msg":"trace[1959492355] transaction","detail":"{read_only:false; response_revision:26796; number_of_response:1; }","duration":"250.883128ms","start":"2026-07-13T13:23:58.39968Z","end":"2026-07-13T13:23:58.650564Z","steps":["trace[1959492355] 'process raft request' (duration: 250.494158ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:58.854717Z","caller":"traceutil/trace.go:171","msg":"trace[976043404] transaction","detail":"{read_only:false; response_revision:26799; number_of_response:1; }","duration":"103.626877ms","start":"2026-07-13T13:23:58.751067Z","end":"2026-07-13T13:23:58.854694Z","steps":["trace[976043404] 'process raft request' (duration: 103.34406ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:58.854731Z","caller":"traceutil/trace.go:171","msg":"trace[1129642847] transaction","detail":"{read_only:false; response_revision:26798; number_of_response:1; }","duration":"104.811521ms","start":"2026-07-13T13:23:58.749884Z","end":"2026-07-13T13:23:58.854695Z","steps":["trace[1129642847] 'process raft request' (duration: 27.759026ms)","trace[1129642847] 'compare' (duration: 76.631723ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:23:59.082443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.17581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-13T13:23:59.082721Z","caller":"traceutil/trace.go:171","msg":"trace[1694258810] linearizableReadLoop","detail":"{readStateIndex:27686; appliedIndex:27684; }","duration":"228.226794ms","start":"2026-07-13T13:23:58.854458Z","end":"2026-07-13T13:23:59.082685Z","steps":["trace[1694258810] 'read index received' (duration: 101.680254ms)","trace[1694258810] 'applied index is now lower than readState.Index' (duration: 126.54561ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:59.082832Z","caller":"traceutil/trace.go:171","msg":"trace[196760907] transaction","detail":"{read_only:false; response_revision:26800; number_of_response:1; }","duration":"253.921022ms","start":"2026-07-13T13:23:58.828899Z","end":"2026-07-13T13:23:59.08282Z","steps":["trace[196760907] 'process raft request' (duration: 127.25698ms)","trace[196760907] 'compare' (duration: 125.944183ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T13:23:59.083172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.547937ms","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-07-13T13:23:59.083216Z","caller":"traceutil/trace.go:171","msg":"trace[2045484833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26801; }","duration":"214.631089ms","start":"2026-07-13T13:23:58.868575Z","end":"2026-07-13T13:23:59.083206Z","steps":["trace[2045484833] 'agreement among raft nodes before linearized reading' (duration: 214.457254ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:59.083193Z","caller":"traceutil/trace.go:171","msg":"trace[1119576226] transaction","detail":"{read_only:false; response_revision:26801; number_of_response:1; }","duration":"254.145339ms","start":"2026-07-13T13:23:58.829016Z","end":"2026-07-13T13:23:59.083161Z","steps":["trace[1119576226] 'process raft request' (duration: 253.567893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:59.083337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.360651ms","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-07-13T13:23:59.08356Z","caller":"traceutil/trace.go:171","msg":"trace[582303152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26801; }","duration":"214.562517ms","start":"2026-07-13T13:23:58.86896Z","end":"2026-07-13T13:23:59.083523Z","steps":["trace[582303152] 'agreement among raft nodes before linearized reading' (duration: 214.31184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:59.084557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.40629ms","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-07-13T13:23:59.084627Z","caller":"traceutil/trace.go:171","msg":"trace[1275985783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26801; }","duration":"215.491213ms","start":"2026-07-13T13:23:58.869119Z","end":"2026-07-13T13:23:59.084611Z","steps":["trace[1275985783] 'agreement among raft nodes before linearized reading' (duration: 214.145256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:23:59.085524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.344443ms","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-07-13T13:23:59.085572Z","caller":"traceutil/trace.go:171","msg":"trace[410964388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26801; }","duration":"258.429077ms","start":"2026-07-13T13:23:58.827126Z","end":"2026-07-13T13:23:59.085555Z","steps":["trace[410964388] 'agreement among raft nodes before linearized reading' (duration: 255.760203ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:59.232279Z","caller":"traceutil/trace.go:171","msg":"trace[820057139] transaction","detail":"{read_only:false; response_revision:26803; number_of_response:1; }","duration":"144.549016ms","start":"2026-07-13T13:23:59.087694Z","end":"2026-07-13T13:23:59.232243Z","steps":["trace[820057139] 'process raft request' (duration: 103.260597ms)","trace[820057139] 'compare' (duration: 40.897328ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:23:59.232736Z","caller":"traceutil/trace.go:171","msg":"trace[455211493] transaction","detail":"{read_only:false; response_revision:26804; number_of_response:1; }","duration":"143.800545ms","start":"2026-07-13T13:23:59.088893Z","end":"2026-07-13T13:23:59.232694Z","steps":["trace[455211493] 'process raft request' (duration: 143.26435ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:59.233013Z","caller":"traceutil/trace.go:171","msg":"trace[2056332786] transaction","detail":"{read_only:false; response_revision:26805; number_of_response:1; }","duration":"143.891068ms","start":"2026-07-13T13:23:59.089071Z","end":"2026-07-13T13:23:59.232962Z","steps":["trace[2056332786] 'process raft request' (duration: 143.499058ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:23:59.654351Z","caller":"traceutil/trace.go:171","msg":"trace[2071044829] transaction","detail":"{read_only:false; response_revision:26806; number_of_response:1; }","duration":"138.559391ms","start":"2026-07-13T13:23:59.515766Z","end":"2026-07-13T13:23:59.654325Z","steps":["trace[2071044829] 'process raft request' (duration: 138.416247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:24:00.094738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.122642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:24:00.095391Z","caller":"traceutil/trace.go:171","msg":"trace[1105752962] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26807; }","duration":"112.865952ms","start":"2026-07-13T13:23:59.982497Z","end":"2026-07-13T13:24:00.095363Z","steps":["trace[1105752962] 'count revisions from in-memory index tree' (duration: 112.012839ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:26:03.863519Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25161} {"level":"info","ts":"2026-07-13T13:26:04.010115Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25161,"took":"142.236726ms","hash":1404441907,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":38178816,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-13T13:26:04.010287Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1404441907,"revision":25161,"compact-revision":21991} {"level":"info","ts":"2026-07-13T13:27:39.359978Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fed19d9291630e09","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-13T13:27:39.364325Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-13T13:27:39.364394Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-13T13:28:01.742129Z","caller":"traceutil/trace.go:171","msg":"trace[1946148558] transaction","detail":"{read_only:false; response_revision:29271; number_of_response:1; }","duration":"139.803689ms","start":"2026-07-13T13:28:01.602306Z","end":"2026-07-13T13:28:01.74211Z","steps":["trace[1946148558] 'process raft request' (duration: 139.681515ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:28:01.83414Z","caller":"traceutil/trace.go:171","msg":"trace[940601959] transaction","detail":"{read_only:false; response_revision:29272; number_of_response:1; }","duration":"205.549562ms","start":"2026-07-13T13:28:01.628563Z","end":"2026-07-13T13:28:01.834113Z","steps":["trace[940601959] 'process raft request' (duration: 184.345824ms)","trace[940601959] 'compare' (duration: 21.017683ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:28:01.834333Z","caller":"traceutil/trace.go:171","msg":"trace[496442649] linearizableReadLoop","detail":"{readStateIndex:30219; appliedIndex:30217; }","duration":"126.370046ms","start":"2026-07-13T13:28:01.707932Z","end":"2026-07-13T13:28:01.834302Z","steps":["trace[496442649] 'read index received' (duration: 34.364254ms)","trace[496442649] 'applied index is now lower than readState.Index' (duration: 92.003512ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:28:01.834425Z","caller":"traceutil/trace.go:171","msg":"trace[1974855453] transaction","detail":"{read_only:false; response_revision:29273; number_of_response:1; }","duration":"203.861106ms","start":"2026-07-13T13:28:01.630527Z","end":"2026-07-13T13:28:01.834388Z","steps":["trace[1974855453] 'process raft request' (duration: 203.537757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:28:01.834563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.610963ms","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-07-13T13:28:01.83494Z","caller":"traceutil/trace.go:171","msg":"trace[277428423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29273; }","duration":"127.024103ms","start":"2026-07-13T13:28:01.707898Z","end":"2026-07-13T13:28:01.834922Z","steps":["trace[277428423] 'agreement among raft nodes before linearized reading' (duration: 126.514509ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:31:03.870275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28073} {"level":"info","ts":"2026-07-13T13:31:04.007382Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28073,"took":"132.291407ms","hash":472517113,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":34353152,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-13T13:31:04.007448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":472517113,"revision":28073,"compact-revision":25161} {"level":"info","ts":"2026-07-13T13:36:03.879169Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30683} {"level":"info","ts":"2026-07-13T13:36:04.012843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30683,"took":"129.438888ms","hash":1681112060,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":30908416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-13T13:36:04.012931Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1681112060,"revision":30683,"compact-revision":28073} {"level":"info","ts":"2026-07-13T13:41:03.892354Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32949} {"level":"info","ts":"2026-07-13T13:41:04.04471Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32949,"took":"145.439235ms","hash":745982985,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":30527488,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-13T13:41:04.044796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":745982985,"revision":32949,"compact-revision":30683} {"level":"info","ts":"2026-07-13T13:46:03.898666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35196} {"level":"info","ts":"2026-07-13T13:46:04.035578Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35196,"took":"133.616412ms","hash":486396756,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":31023104,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-13T13:46:04.036175Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":486396756,"revision":35196,"compact-revision":32949} {"level":"warn","ts":"2026-07-13T13:47:14.537758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514806485727993,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-13T13:47:15.038933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514806485727993,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-13T13:47:15.128207Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.97671083s","expected-duration":"1s"} {"level":"info","ts":"2026-07-13T13:47:15.128849Z","caller":"traceutil/trace.go:171","msg":"trace[701146678] transaction","detail":"{read_only:false; number_of_response:1; response_revision:38056; }","duration":"1.977406819s","start":"2026-07-13T13:47:13.151406Z","end":"2026-07-13T13:47:15.128813Z","steps":["trace[701146678] 'process raft request' (duration: 1.977181473s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.129116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:13.151391Z","time spent":"1.977553683s","remote":"127.0.0.1:55734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":43,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-07-13T13:47:15.142532Z","caller":"traceutil/trace.go:171","msg":"trace[1456659011] linearizableReadLoop","detail":"{readStateIndex:39260; appliedIndex:39249; }","duration":"1.105063653s","start":"2026-07-13T13:47:14.037436Z","end":"2026-07-13T13:47:15.1425Z","steps":["trace[1456659011] 'read index received' (duration: 1.091142846s)","trace[1456659011] 'applied index is now lower than readState.Index' (duration: 13.919607ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T13:47:15.142675Z","caller":"traceutil/trace.go:171","msg":"trace[1395189131] transaction","detail":"{read_only:false; response_revision:38065; number_of_response:1; }","duration":"359.359794ms","start":"2026-07-13T13:47:14.783306Z","end":"2026-07-13T13:47:15.142666Z","steps":["trace[1395189131] 'process raft request' (duration: 359.122807ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:47:15.142677Z","caller":"traceutil/trace.go:171","msg":"trace[101612833] transaction","detail":"{read_only:false; response_revision:38064; number_of_response:1; }","duration":"368.952711ms","start":"2026-07-13T13:47:14.773675Z","end":"2026-07-13T13:47:15.142628Z","steps":["trace[101612833] 'process raft request' (duration: 368.650382ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:47:15.142752Z","caller":"traceutil/trace.go:171","msg":"trace[895984153] transaction","detail":"{read_only:false; response_revision:38058; number_of_response:1; }","duration":"1.880685641s","start":"2026-07-13T13:47:13.262035Z","end":"2026-07-13T13:47:15.142721Z","steps":["trace[895984153] 'process raft request' (duration: 1.880102845s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.142841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.783295Z","time spent":"359.414326ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T13:47:15.142846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105363321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-13T13:47:15.142909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.773639Z","time spent":"369.151236ms","remote":"127.0.0.1:52864","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-07-13T13:47:15.142936Z","caller":"traceutil/trace.go:171","msg":"trace[1724859284] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:38066; }","duration":"1.105500485s","start":"2026-07-13T13:47:14.037417Z","end":"2026-07-13T13:47:15.142917Z","steps":["trace[1724859284] 'agreement among raft nodes before linearized reading' (duration: 1.10532006s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.143086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.0374Z","time spent":"1.105650409s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-07-13T13:47:15.143127Z","caller":"traceutil/trace.go:171","msg":"trace[2136516175] transaction","detail":"{read_only:false; response_revision:38062; number_of_response:1; }","duration":"459.083465ms","start":"2026-07-13T13:47:14.684033Z","end":"2026-07-13T13:47:15.143116Z","steps":["trace[2136516175] 'process raft request' (duration: 458.240072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.143183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.684019Z","time spent":"459.136406ms","remote":"127.0.0.1:52864","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-07-13T13:47:15.143272Z","caller":"traceutil/trace.go:171","msg":"trace[1613128423] transaction","detail":"{read_only:false; response_revision:38063; number_of_response:1; }","duration":"373.685122ms","start":"2026-07-13T13:47:14.769577Z","end":"2026-07-13T13:47:15.143262Z","steps":["trace[1613128423] 'process raft request' (duration: 372.718505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.143322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.769543Z","time spent":"373.753664ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T13:47:15.143334Z","caller":"traceutil/trace.go:171","msg":"trace[1388583530] transaction","detail":"{read_only:false; response_revision:38057; number_of_response:1; }","duration":"1.909244995s","start":"2026-07-13T13:47:13.234078Z","end":"2026-07-13T13:47:15.143323Z","steps":["trace[1388583530] 'process raft request' (duration: 1.907857976s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.143391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:13.234042Z","time spent":"1.909324617s","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T13:47:15.143461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.807273ms","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-07-13T13:47:15.1435Z","caller":"traceutil/trace.go:171","msg":"trace[497123009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:38066; }","duration":"460.885635ms","start":"2026-07-13T13:47:14.682606Z","end":"2026-07-13T13:47:15.143492Z","steps":["trace[497123009] 'agreement among raft nodes before linearized reading' (duration: 460.768981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.143524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.682587Z","time spent":"460.931206ms","remote":"127.0.0.1:52864","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-07-13T13:47:15.143592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105974248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-13T13:47:15.14363Z","caller":"traceutil/trace.go:171","msg":"trace[998287516] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:38066; }","duration":"1.106027669s","start":"2026-07-13T13:47:14.037593Z","end":"2026-07-13T13:47:15.14362Z","steps":["trace[998287516] 'agreement among raft nodes before linearized reading' (duration: 1.105934637s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.143652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.037585Z","time spent":"1.106061731s","remote":"127.0.0.1:34058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-13T13:47:15.143672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106084951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:47:15.14371Z","caller":"traceutil/trace.go:171","msg":"trace[310390038] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:38066; }","duration":"1.106171893s","start":"2026-07-13T13:47:14.037526Z","end":"2026-07-13T13:47:15.143698Z","steps":["trace[310390038] 'agreement among raft nodes before linearized reading' (duration: 1.106114652s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.143745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.037497Z","time spent":"1.106241385s","remote":"127.0.0.1:47012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-07-13T13:47:15.142945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:13.261992Z","time spent":"1.880840135s","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T13:47:15.143865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.156525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-13T13:47:15.143899Z","caller":"traceutil/trace.go:171","msg":"trace[1052212300] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:38066; }","duration":"839.252358ms","start":"2026-07-13T13:47:14.304642Z","end":"2026-07-13T13:47:15.143894Z","steps":["trace[1052212300] 'agreement among raft nodes before linearized reading' (duration: 839.116884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.143913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.304614Z","time spent":"839.295818ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-13T13:47:15.14295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105508095s","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":"warn","ts":"2026-07-13T13:47:15.14405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106439641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:47:15.144068Z","caller":"traceutil/trace.go:171","msg":"trace[1108582273] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:38066; }","duration":"1.106512032s","start":"2026-07-13T13:47:14.03755Z","end":"2026-07-13T13:47:15.144062Z","steps":["trace[1108582273] 'agreement among raft nodes before linearized reading' (duration: 1.106480332s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.144081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.037528Z","time spent":"1.106549814s","remote":"127.0.0.1:37462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"info","ts":"2026-07-13T13:47:15.144192Z","caller":"traceutil/trace.go:171","msg":"trace[2132425326] transaction","detail":"{read_only:false; response_revision:38059; number_of_response:1; }","duration":"1.102220624s","start":"2026-07-13T13:47:14.041938Z","end":"2026-07-13T13:47:15.144159Z","steps":["trace[2132425326] 'process raft request' (duration: 1.100235409s)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:47:15.144254Z","caller":"traceutil/trace.go:171","msg":"trace[6061022] transaction","detail":"{read_only:false; response_revision:38060; number_of_response:1; }","duration":"1.06034656s","start":"2026-07-13T13:47:14.083897Z","end":"2026-07-13T13:47:15.144244Z","steps":["trace[6061022] 'process raft request' (duration: 1.058309704s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.14432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.083876Z","time spent":"1.060411762s","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T13:47:15.144334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.041917Z","time spent":"1.102334757s","remote":"127.0.0.1:52864","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-07-13T13:47:15.14613Z","caller":"traceutil/trace.go:171","msg":"trace[1588932536] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:38066; }","duration":"1.106647757s","start":"2026-07-13T13:47:14.037391Z","end":"2026-07-13T13:47:15.144039Z","steps":["trace[1588932536] 'agreement among raft nodes before linearized reading' (duration: 1.105285309s)"],"step_count":1} {"level":"info","ts":"2026-07-13T13:47:15.142997Z","caller":"traceutil/trace.go:171","msg":"trace[42839923] transaction","detail":"{read_only:false; response_revision:38066; number_of_response:1; }","duration":"228.256142ms","start":"2026-07-13T13:47:14.91473Z","end":"2026-07-13T13:47:15.142986Z","steps":["trace[42839923] 'process raft request' (duration: 227.735257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.144019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052132572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-13T13:47:15.146257Z","caller":"traceutil/trace.go:171","msg":"trace[224626542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:38066; }","duration":"1.054392515s","start":"2026-07-13T13:47:14.091848Z","end":"2026-07-13T13:47:15.146241Z","steps":["trace[224626542] 'agreement among raft nodes before linearized reading' (duration: 1.052156003s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.146292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.091834Z","time spent":"1.054446596s","remote":"127.0.0.1:52650","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-07-13T13:47:15.146435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.037369Z","time spent":"1.109056224s","remote":"127.0.0.1:52864","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-07-13T13:47:15.142637Z","caller":"traceutil/trace.go:171","msg":"trace[1125202595] transaction","detail":"{read_only:false; response_revision:38061; number_of_response:1; }","duration":"570.359846ms","start":"2026-07-13T13:47:14.572228Z","end":"2026-07-13T13:47:15.142587Z","steps":["trace[1125202595] 'process raft request' (duration: 570.013557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T13:47:15.149484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T13:47:14.572189Z","time spent":"577.209607ms","remote":"127.0.0.1:52864","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-07-13T13:48:53.553843Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fed19d9291630e09","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-13T13:48:53.560716Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-13T13:48:53.560915Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-13T13:51:03.904296Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37507} {"level":"info","ts":"2026-07-13T13:51:04.027119Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37507,"took":"112.1581ms","hash":3112193156,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":30801920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-13T13:51:04.027179Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3112193156,"revision":37507,"compact-revision":35196}