{"level":"warn","ts":"2026-03-13T23:03:35.847089Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T23:03:35.847187Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.6: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.6:2380","--initial-cluster=instance=https://199.204.45.6:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.6:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.6: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-03-13T23:03:35.847266Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T23:03:35.847285Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-03-13T23:03:35.847311Z","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-03-13T23:03:35.848Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"]} {"level":"info","ts":"2026-03-13T23:03:35.848093Z","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.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.6: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-03-13T23:03:35.852492Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.12816ms"} {"level":"info","ts":"2026-03-13T23:03:35.86789Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4ec288cbdba84d36","cluster-id":"9b45faacd38a820f"} {"level":"info","ts":"2026-03-13T23:03:35.868024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T23:03:35.868051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 0"} {"level":"info","ts":"2026-03-13T23:03:35.868064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4ec288cbdba84d36 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T23:03:35.868075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 1"} {"level":"info","ts":"2026-03-13T23:03:35.868124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"warn","ts":"2026-03-13T23:03:35.876409Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T23:03:35.880382Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T23:03:35.882612Z","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-03-13T23:03:35.885876Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4ec288cbdba84d36","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T23:03:35.886108Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4ec288cbdba84d36","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T23:03:35.886178Z","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-03-13T23:03:35.886295Z","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-03-13T23:03:35.886306Z","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-03-13T23:03:35.886872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"info","ts":"2026-03-13T23:03:35.887029Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","added-peer-id":"4ec288cbdba84d36","added-peer-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-03-13T23:03:35.8903Z","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-03-13T23:03:35.890511Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-03-13T23:03:35.890595Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4ec288cbdba84d36","initial-advertise-peer-urls":["https://199.204.45.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T23:03:35.89063Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-03-13T23:03:35.890707Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T23:03:35.969114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T23:03:35.969216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T23:03:35.969261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgPreVoteResp from 4ec288cbdba84d36 at term 1"} {"level":"info","ts":"2026-03-13T23:03:35.969294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became candidate at term 2"} {"level":"info","ts":"2026-03-13T23:03:35.969307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgVoteResp from 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-03-13T23:03:35.969333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became leader at term 2"} {"level":"info","ts":"2026-03-13T23:03:35.96935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4ec288cbdba84d36 elected leader 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-03-13T23:03:35.970305Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:03:35.972131Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:03:35.972266Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:03:35.972291Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4ec288cbdba84d36","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.6:2379]}","request-path":"/0/members/4ec288cbdba84d36/attributes","cluster-id":"9b45faacd38a820f","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T23:03:35.972311Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:03:35.972402Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:03:35.972308Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:03:35.972584Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T23:03:35.972636Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T23:03:35.975745Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-13T23:03:35.976041Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.6:2379"} {"level":"info","ts":"2026-03-13T23:03:40.359182Z","caller":"traceutil/trace.go:171","msg":"trace[771785435] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"134.32196ms","start":"2026-03-13T23:03:40.224831Z","end":"2026-03-13T23:03:40.359153Z","steps":["trace[771785435] 'process raft request' (duration: 134.087404ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:47.703738Z","caller":"traceutil/trace.go:171","msg":"trace[2065953811] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"107.43688ms","start":"2026-03-13T23:03:47.596278Z","end":"2026-03-13T23:03:47.703715Z","steps":["trace[2065953811] 'process raft request' (duration: 107.301206ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:03:53.68887Z","caller":"traceutil/trace.go:171","msg":"trace[979586395] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"158.619381ms","start":"2026-03-13T23:03:53.530231Z","end":"2026-03-13T23:03:53.68885Z","steps":["trace[979586395] 'process raft request' (duration: 158.495178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:06.109288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.571157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-13T23:04:06.109326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.26074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-13T23:04:06.109385Z","caller":"traceutil/trace.go:171","msg":"trace[1916801045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"226.728292ms","start":"2026-03-13T23:04:05.882642Z","end":"2026-03-13T23:04:06.109371Z","steps":["trace[1916801045] 'range keys from in-memory index tree' (duration: 226.418153ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:06.109409Z","caller":"traceutil/trace.go:171","msg":"trace[1649553902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"223.390784ms","start":"2026-03-13T23:04:05.886001Z","end":"2026-03-13T23:04:06.109392Z","steps":["trace[1649553902] 'range keys from in-memory index tree' (duration: 223.126007ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:06.263438Z","caller":"traceutil/trace.go:171","msg":"trace[1353388947] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"149.168682ms","start":"2026-03-13T23:04:06.11424Z","end":"2026-03-13T23:04:06.263408Z","steps":["trace[1353388947] 'process raft request' (duration: 149.026358ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:06.434579Z","caller":"traceutil/trace.go:171","msg":"trace[1016640181] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"125.689022ms","start":"2026-03-13T23:04:06.308865Z","end":"2026-03-13T23:04:06.434554Z","steps":["trace[1016640181] 'process raft request' (duration: 125.532888ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:12.521279Z","caller":"traceutil/trace.go:171","msg":"trace[457317394] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"122.436235ms","start":"2026-03-13T23:04:12.398798Z","end":"2026-03-13T23:04:12.521235Z","steps":["trace[457317394] 'process raft request' (duration: 122.006314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:12.835437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.621363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:04:12.835616Z","caller":"traceutil/trace.go:171","msg":"trace[221650807] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"309.448998ms","start":"2026-03-13T23:04:12.52611Z","end":"2026-03-13T23:04:12.835559Z","steps":["trace[221650807] 'process raft request' (duration: 139.014964ms)","trace[221650807] 'compare' (duration: 169.390016ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:04:12.83571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:12.52609Z","time spent":"309.5729ms","remote":"127.0.0.1:57206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:04:12.840889Z","caller":"traceutil/trace.go:171","msg":"trace[2007247711] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"299.33016ms","start":"2026-03-13T23:04:12.541539Z","end":"2026-03-13T23:04:12.840869Z","steps":["trace[2007247711] 'process raft request' (duration: 299.300319ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:12.840929Z","caller":"traceutil/trace.go:171","msg":"trace[1525393545] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"305.894984ms","start":"2026-03-13T23:04:12.535013Z","end":"2026-03-13T23:04:12.840908Z","steps":["trace[1525393545] 'process raft request' (duration: 305.721359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:12.841002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:12.534979Z","time spent":"305.988446ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:04:13.504617Z","caller":"traceutil/trace.go:171","msg":"trace[1318320235] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"640.769984ms","start":"2026-03-13T23:04:12.863823Z","end":"2026-03-13T23:04:13.504593Z","steps":["trace[1318320235] 'process raft request' (duration: 631.385336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:13.504746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:12.863807Z","time spent":"640.876916ms","remote":"127.0.0.1:57006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":863,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T23:04:13.504708Z","caller":"traceutil/trace.go:171","msg":"trace[549890036] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"640.727742ms","start":"2026-03-13T23:04:12.863951Z","end":"2026-03-13T23:04:13.504678Z","steps":["trace[549890036] 'process raft request' (duration: 640.568508ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:13.505259Z","caller":"traceutil/trace.go:171","msg":"trace[1272997270] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"640.382033ms","start":"2026-03-13T23:04:12.86479Z","end":"2026-03-13T23:04:13.505172Z","steps":["trace[1272997270] 'process raft request' (duration: 639.810848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:13.505444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:12.863943Z","time spent":"641.414571ms","remote":"127.0.0.1:57178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3148,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:04:13.505943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:12.864781Z","time spent":"640.6499ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3329,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:04:33.111286Z","caller":"traceutil/trace.go:171","msg":"trace[761974109] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"239.697265ms","start":"2026-03-13T23:04:32.871563Z","end":"2026-03-13T23:04:33.11126Z","steps":["trace[761974109] 'read index received' (duration: 233.769208ms)","trace[761974109] 'applied index is now lower than readState.Index' (duration: 5.926647ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:04:33.111441Z","caller":"traceutil/trace.go:171","msg":"trace[673640811] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"338.364007ms","start":"2026-03-13T23:04:32.773035Z","end":"2026-03-13T23:04:33.111399Z","steps":["trace[673640811] 'process raft request' (duration: 332.294727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:33.111596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.371137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-13T23:04:33.11167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:04:32.773009Z","time spent":"338.559583ms","remote":"127.0.0.1:57382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3875,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:04:33.111704Z","caller":"traceutil/trace.go:171","msg":"trace[1237371848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"164.535251ms","start":"2026-03-13T23:04:32.947148Z","end":"2026-03-13T23:04:33.111683Z","steps":["trace[1237371848] 'agreement among raft nodes before linearized reading' (duration: 164.294375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:04:33.111764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.199648ms","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-03-13T23:04:33.111903Z","caller":"traceutil/trace.go:171","msg":"trace[514692056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:889; }","duration":"240.424503ms","start":"2026-03-13T23:04:32.871462Z","end":"2026-03-13T23:04:33.111886Z","steps":["trace[514692056] 'agreement among raft nodes before linearized reading' (duration: 239.955071ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:04:58.447324Z","caller":"traceutil/trace.go:171","msg":"trace[1570633283] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"135.283069ms","start":"2026-03-13T23:04:58.312001Z","end":"2026-03-13T23:04:58.447284Z","steps":["trace[1570633283] 'process raft request' (duration: 76.138558ms)","trace[1570633283] 'compare' (duration: 58.992817ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:05:25.367942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.924018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8f3acd17-8e45-455a-9ba3-174f984d0e92\" ","response":"range_response_count:1 size:3625"} {"level":"warn","ts":"2026-03-13T23:05:25.368013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.5707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:05:25.368028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.140809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-03-13T23:05:25.368093Z","caller":"traceutil/trace.go:171","msg":"trace[1976343338] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8f3acd17-8e45-455a-9ba3-174f984d0e92; range_end:; response_count:1; response_revision:1555; }","duration":"419.130203ms","start":"2026-03-13T23:05:24.948929Z","end":"2026-03-13T23:05:25.36806Z","steps":["trace[1976343338] 'range keys from in-memory index tree' (duration: 418.723592ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:25.368094Z","caller":"traceutil/trace.go:171","msg":"trace[1784947585] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1555; }","duration":"144.732105ms","start":"2026-03-13T23:05:25.223344Z","end":"2026-03-13T23:05:25.368076Z","steps":["trace[1784947585] 'count revisions from in-memory index tree' (duration: 144.496888ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:25.368124Z","caller":"traceutil/trace.go:171","msg":"trace[1101342236] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1555; }","duration":"277.293223ms","start":"2026-03-13T23:05:25.090807Z","end":"2026-03-13T23:05:25.3681Z","steps":["trace[1101342236] 'range keys from in-memory index tree' (duration: 276.935994ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:25.368412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:05:24.948911Z","time spent":"419.477622ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8f3acd17-8e45-455a-9ba3-174f984d0e92\" "} {"level":"info","ts":"2026-03-13T23:05:25.388638Z","caller":"traceutil/trace.go:171","msg":"trace[1841723745] linearizableReadLoop","detail":"{readStateIndex:1623; appliedIndex:1622; }","duration":"119.971544ms","start":"2026-03-13T23:05:25.268646Z","end":"2026-03-13T23:05:25.388618Z","steps":["trace[1841723745] 'read index received' (duration: 119.918553ms)","trace[1841723745] 'applied index is now lower than readState.Index' (duration: 51.471µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:05:25.3887Z","caller":"traceutil/trace.go:171","msg":"trace[302394978] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"134.9283ms","start":"2026-03-13T23:05:25.253743Z","end":"2026-03-13T23:05:25.388671Z","steps":["trace[302394978] 'process raft request' (duration: 134.740505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:25.388893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.24515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-03-13T23:05:25.388971Z","caller":"traceutil/trace.go:171","msg":"trace[242762445] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1556; }","duration":"120.355753ms","start":"2026-03-13T23:05:25.26858Z","end":"2026-03-13T23:05:25.388936Z","steps":["trace[242762445] 'agreement among raft nodes before linearized reading' (duration: 120.162688ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:37.732158Z","caller":"traceutil/trace.go:171","msg":"trace[721973040] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"153.338018ms","start":"2026-03-13T23:05:37.578793Z","end":"2026-03-13T23:05:37.732131Z","steps":["trace[721973040] 'process raft request' (duration: 153.167933ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:37.737276Z","caller":"traceutil/trace.go:171","msg":"trace[669669849] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"158.117251ms","start":"2026-03-13T23:05:37.57914Z","end":"2026-03-13T23:05:37.737258Z","steps":["trace[669669849] 'process raft request' (duration: 157.963467ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:14.755626Z","caller":"traceutil/trace.go:171","msg":"trace[66708521] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"286.003779ms","start":"2026-03-13T23:06:14.4696Z","end":"2026-03-13T23:06:14.755604Z","steps":["trace[66708521] 'process raft request' (duration: 285.860936ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:14.759842Z","caller":"traceutil/trace.go:171","msg":"trace[1721464500] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"227.484905ms","start":"2026-03-13T23:06:14.532329Z","end":"2026-03-13T23:06:14.759813Z","steps":["trace[1721464500] 'process raft request' (duration: 227.311681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:14.997122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.33225ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-13T23:06:14.997189Z","caller":"traceutil/trace.go:171","msg":"trace[16246538] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1926; }","duration":"288.150125ms","start":"2026-03-13T23:06:14.709026Z","end":"2026-03-13T23:06:14.997176Z","steps":["trace[16246538] 'read index received' (duration: 46.449632ms)","trace[16246538] 'applied index is now lower than readState.Index' (duration: 241.699443ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:06:14.997431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.966469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:14.997452Z","caller":"traceutil/trace.go:171","msg":"trace[1350270950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"247.033071ms","start":"2026-03-13T23:06:14.750413Z","end":"2026-03-13T23:06:14.997446Z","steps":["trace[1350270950] 'agreement among raft nodes before linearized reading' (duration: 246.98888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:14.997506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.453312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-03-13T23:06:14.997577Z","caller":"traceutil/trace.go:171","msg":"trace[394725389] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1850; }","duration":"288.581296ms","start":"2026-03-13T23:06:14.708979Z","end":"2026-03-13T23:06:14.99756Z","steps":["trace[394725389] 'agreement among raft nodes before linearized reading' (duration: 288.384561ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:01.140869Z","caller":"traceutil/trace.go:171","msg":"trace[912697243] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"109.725644ms","start":"2026-03-13T23:07:01.031119Z","end":"2026-03-13T23:07:01.140845Z","steps":["trace[912697243] 'process raft request' (duration: 109.580971ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:35.675583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.222495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:07:35.675739Z","caller":"traceutil/trace.go:171","msg":"trace[725341647] linearizableReadLoop","detail":"{readStateIndex:2544; appliedIndex:2543; }","duration":"255.535587ms","start":"2026-03-13T23:07:35.420178Z","end":"2026-03-13T23:07:35.675714Z","steps":["trace[725341647] 'read index received' (duration: 104.926362ms)","trace[725341647] 'applied index is now lower than readState.Index' (duration: 150.607465ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:07:35.675839Z","caller":"traceutil/trace.go:171","msg":"trace[445859832] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"266.761763ms","start":"2026-03-13T23:07:35.409049Z","end":"2026-03-13T23:07:35.675811Z","steps":["trace[445859832] 'process raft request' (duration: 116.143098ms)","trace[445859832] 'compare' (duration: 150.065641ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:07:35.675961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.781874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"warn","ts":"2026-03-13T23:07:35.67597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.517504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:07:35.676025Z","caller":"traceutil/trace.go:171","msg":"trace[101394200] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2442; }","duration":"255.887236ms","start":"2026-03-13T23:07:35.420121Z","end":"2026-03-13T23:07:35.676009Z","steps":["trace[101394200] 'agreement among raft nodes before linearized reading' (duration: 255.709022ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:35.67605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.043471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-13T23:07:35.676127Z","caller":"traceutil/trace.go:171","msg":"trace[1466129437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2442; }","duration":"226.146663ms","start":"2026-03-13T23:07:35.449965Z","end":"2026-03-13T23:07:35.676111Z","steps":["trace[1466129437] 'agreement among raft nodes before linearized reading' (duration: 225.904267ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:35.676168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.298472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:07:35.676219Z","caller":"traceutil/trace.go:171","msg":"trace[94359145] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2442; }","duration":"165.375434ms","start":"2026-03-13T23:07:35.510831Z","end":"2026-03-13T23:07:35.676206Z","steps":["trace[94359145] 'agreement among raft nodes before linearized reading' (duration: 165.270551ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:35.676066Z","caller":"traceutil/trace.go:171","msg":"trace[1831712028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2442; }","duration":"188.656636ms","start":"2026-03-13T23:07:35.487381Z","end":"2026-03-13T23:07:35.676037Z","steps":["trace[1831712028] 'agreement among raft nodes before linearized reading' (duration: 188.514432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:35.676281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.329306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:07:35.676354Z","caller":"traceutil/trace.go:171","msg":"trace[1739941867] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2442; }","duration":"205.455729ms","start":"2026-03-13T23:07:35.470878Z","end":"2026-03-13T23:07:35.676333Z","steps":["trace[1739941867] 'agreement among raft nodes before linearized reading' (duration: 205.304295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:35.676354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.309803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-13T23:07:35.676421Z","caller":"traceutil/trace.go:171","msg":"trace[1358275649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2442; }","duration":"219.402306ms","start":"2026-03-13T23:07:35.457003Z","end":"2026-03-13T23:07:35.676405Z","steps":["trace[1358275649] 'agreement among raft nodes before linearized reading' (duration: 219.245262ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:35.787413Z","caller":"traceutil/trace.go:171","msg":"trace[2085601371] transaction","detail":"{read_only:false; response_revision:2444; number_of_response:1; }","duration":"106.346639ms","start":"2026-03-13T23:07:35.68102Z","end":"2026-03-13T23:07:35.787367Z","steps":["trace[2085601371] 'process raft request' (duration: 106.304288ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:35.787554Z","caller":"traceutil/trace.go:171","msg":"trace[17385435] transaction","detail":"{read_only:false; response_revision:2443; number_of_response:1; }","duration":"107.296525ms","start":"2026-03-13T23:07:35.680251Z","end":"2026-03-13T23:07:35.787547Z","steps":["trace[17385435] 'process raft request' (duration: 105.697073ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:09:57.252821Z","caller":"traceutil/trace.go:171","msg":"trace[2135291864] transaction","detail":"{read_only:false; response_revision:3321; number_of_response:1; }","duration":"227.543327ms","start":"2026-03-13T23:09:57.025254Z","end":"2026-03-13T23:09:57.252798Z","steps":["trace[2135291864] 'process raft request' (duration: 227.392403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:02.585936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.013392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-03-13T23:10:02.585994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.044577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6fn6\" ","response":"range_response_count:1 size:6523"} {"level":"warn","ts":"2026-03-13T23:10:02.585994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.782494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-13T23:10:02.586047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.761074ms","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-03-13T23:10:02.586089Z","caller":"traceutil/trace.go:171","msg":"trace[1828097934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3343; }","duration":"124.914498ms","start":"2026-03-13T23:10:02.461155Z","end":"2026-03-13T23:10:02.58607Z","steps":["trace[1828097934] 'range keys from in-memory index tree' (duration: 124.520828ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:10:02.586084Z","caller":"traceutil/trace.go:171","msg":"trace[575331243] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3343; }","duration":"130.200358ms","start":"2026-03-13T23:10:02.45585Z","end":"2026-03-13T23:10:02.58605Z","steps":["trace[575331243] 'range keys from in-memory index tree' (duration: 129.780257ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:10:02.586142Z","caller":"traceutil/trace.go:171","msg":"trace[1582850109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3343; }","duration":"124.898797ms","start":"2026-03-13T23:10:02.461218Z","end":"2026-03-13T23:10:02.586117Z","steps":["trace[1582850109] 'range keys from in-memory index tree' (duration: 124.539108ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:10:02.586078Z","caller":"traceutil/trace.go:171","msg":"trace[1897247015] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6fn6; range_end:; response_count:1; response_revision:3343; }","duration":"165.190601ms","start":"2026-03-13T23:10:02.42087Z","end":"2026-03-13T23:10:02.586061Z","steps":["trace[1897247015] 'range keys from in-memory index tree' (duration: 164.835992ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:14.434584Z","caller":"traceutil/trace.go:171","msg":"trace[1087904977] transaction","detail":"{read_only:false; response_revision:4147; number_of_response:1; }","duration":"108.639764ms","start":"2026-03-13T23:11:14.325913Z","end":"2026-03-13T23:11:14.434553Z","steps":["trace[1087904977] 'process raft request' (duration: 108.198633ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:14.434589Z","caller":"traceutil/trace.go:171","msg":"trace[938295425] transaction","detail":"{read_only:false; response_revision:4148; number_of_response:1; }","duration":"104.082884ms","start":"2026-03-13T23:11:14.330443Z","end":"2026-03-13T23:11:14.434526Z","steps":["trace[938295425] 'process raft request' (duration: 103.819637ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:19.919608Z","caller":"traceutil/trace.go:171","msg":"trace[225946555] linearizableReadLoop","detail":"{readStateIndex:4482; appliedIndex:4481; }","duration":"140.944264ms","start":"2026-03-13T23:11:19.778647Z","end":"2026-03-13T23:11:19.919592Z","steps":["trace[225946555] 'read index received' (duration: 135.626674ms)","trace[225946555] 'applied index is now lower than readState.Index' (duration: 5.31714ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:19.919711Z","caller":"traceutil/trace.go:171","msg":"trace[1022131066] transaction","detail":"{read_only:false; response_revision:4304; number_of_response:1; }","duration":"167.506201ms","start":"2026-03-13T23:11:19.752183Z","end":"2026-03-13T23:11:19.919689Z","steps":["trace[1022131066] 'process raft request' (duration: 162.083238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:19.919828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.167439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-03-13T23:11:19.919853Z","caller":"traceutil/trace.go:171","msg":"trace[1121425203] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4304; }","duration":"141.229831ms","start":"2026-03-13T23:11:19.778616Z","end":"2026-03-13T23:11:19.919846Z","steps":["trace[1121425203] 'agreement among raft nodes before linearized reading' (duration: 141.17196ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:19.947366Z","caller":"traceutil/trace.go:171","msg":"trace[980000549] transaction","detail":"{read_only:false; response_revision:4306; number_of_response:1; }","duration":"161.527925ms","start":"2026-03-13T23:11:19.785805Z","end":"2026-03-13T23:11:19.947333Z","steps":["trace[980000549] 'process raft request' (duration: 161.430502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:19.947436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.585969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"warn","ts":"2026-03-13T23:11:19.947542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.44883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-03-13T23:11:19.947592Z","caller":"traceutil/trace.go:171","msg":"trace[339117707] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4306; }","duration":"168.776935ms","start":"2026-03-13T23:11:19.778802Z","end":"2026-03-13T23:11:19.947579Z","steps":["trace[339117707] 'agreement among raft nodes before linearized reading' (duration: 168.533539ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:19.947604Z","caller":"traceutil/trace.go:171","msg":"trace[2090208826] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4306; }","duration":"124.549882ms","start":"2026-03-13T23:11:19.823036Z","end":"2026-03-13T23:11:19.947586Z","steps":["trace[2090208826] 'agreement among raft nodes before linearized reading' (duration: 124.303756ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:19.947425Z","caller":"traceutil/trace.go:171","msg":"trace[998771625] transaction","detail":"{read_only:false; response_revision:4305; number_of_response:1; }","duration":"167.372558ms","start":"2026-03-13T23:11:19.780027Z","end":"2026-03-13T23:11:19.9474Z","steps":["trace[998771625] 'process raft request' (duration: 167.05816ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:20.066946Z","caller":"traceutil/trace.go:171","msg":"trace[881151030] transaction","detail":"{read_only:false; response_revision:4307; number_of_response:1; }","duration":"103.309115ms","start":"2026-03-13T23:11:19.963602Z","end":"2026-03-13T23:11:20.066911Z","steps":["trace[881151030] 'process raft request' (duration: 81.884881ms)","trace[881151030] 'compare' (duration: 21.197838ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:21.202627Z","caller":"traceutil/trace.go:171","msg":"trace[1507468581] transaction","detail":"{read_only:false; response_revision:4337; number_of_response:1; }","duration":"120.895156ms","start":"2026-03-13T23:11:21.081709Z","end":"2026-03-13T23:11:21.202604Z","steps":["trace[1507468581] 'process raft request' (duration: 120.731272ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:21.203419Z","caller":"traceutil/trace.go:171","msg":"trace[1538847971] linearizableReadLoop","detail":"{readStateIndex:4518; appliedIndex:4516; }","duration":"101.208409ms","start":"2026-03-13T23:11:21.102189Z","end":"2026-03-13T23:11:21.203398Z","steps":["trace[1538847971] 'read index received' (duration: 100.254244ms)","trace[1538847971] 'applied index is now lower than readState.Index' (duration: 953.195µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:21.203594Z","caller":"traceutil/trace.go:171","msg":"trace[489544426] transaction","detail":"{read_only:false; response_revision:4338; number_of_response:1; }","duration":"120.784994ms","start":"2026-03-13T23:11:21.082767Z","end":"2026-03-13T23:11:21.203552Z","steps":["trace[489544426] 'process raft request' (duration: 120.521067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:21.203744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.540989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6fn6\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-03-13T23:11:21.203791Z","caller":"traceutil/trace.go:171","msg":"trace[648493520] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6fn6; range_end:; response_count:1; response_revision:4338; }","duration":"101.63094ms","start":"2026-03-13T23:11:21.102149Z","end":"2026-03-13T23:11:21.20378Z","steps":["trace[648493520] 'agreement among raft nodes before linearized reading' (duration: 101.406734ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:25.814848Z","caller":"traceutil/trace.go:171","msg":"trace[904097336] transaction","detail":"{read_only:false; response_revision:4491; number_of_response:1; }","duration":"132.536182ms","start":"2026-03-13T23:11:25.682289Z","end":"2026-03-13T23:11:25.814826Z","steps":["trace[904097336] 'process raft request' (duration: 132.415739ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:25.841161Z","caller":"traceutil/trace.go:171","msg":"trace[1607942078] linearizableReadLoop","detail":"{readStateIndex:4676; appliedIndex:4674; }","duration":"131.896996ms","start":"2026-03-13T23:11:25.709242Z","end":"2026-03-13T23:11:25.841139Z","steps":["trace[1607942078] 'read index received' (duration: 105.547223ms)","trace[1607942078] 'applied index is now lower than readState.Index' (duration: 26.348763ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:25.841486Z","caller":"traceutil/trace.go:171","msg":"trace[795162377] transaction","detail":"{read_only:false; response_revision:4492; number_of_response:1; }","duration":"156.79105ms","start":"2026-03-13T23:11:25.684683Z","end":"2026-03-13T23:11:25.841475Z","steps":["trace[795162377] 'process raft request' (duration: 156.261186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:25.841879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.627635ms","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-03-13T23:11:25.84191Z","caller":"traceutil/trace.go:171","msg":"trace[1544624075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4493; }","duration":"132.717407ms","start":"2026-03-13T23:11:25.709185Z","end":"2026-03-13T23:11:25.841902Z","steps":["trace[1544624075] 'agreement among raft nodes before linearized reading' (duration: 132.602874ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:42.85736Z","caller":"traceutil/trace.go:171","msg":"trace[1075625486] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"126.131354ms","start":"2026-03-13T23:11:42.731204Z","end":"2026-03-13T23:11:42.857335Z","steps":["trace[1075625486] 'process raft request' (duration: 126.047822ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:42.857407Z","caller":"traceutil/trace.go:171","msg":"trace[1929287916] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"193.882625ms","start":"2026-03-13T23:11:42.663494Z","end":"2026-03-13T23:11:42.857376Z","steps":["trace[1929287916] 'process raft request' (duration: 149.457738ms)","trace[1929287916] 'compare' (duration: 43.970695ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:43.206488Z","caller":"traceutil/trace.go:171","msg":"trace[589850713] linearizableReadLoop","detail":"{readStateIndex:5010; appliedIndex:5009; }","duration":"342.821848ms","start":"2026-03-13T23:11:42.863647Z","end":"2026-03-13T23:11:43.206469Z","steps":["trace[589850713] 'read index received' (duration: 342.662284ms)","trace[589850713] 'applied index is now lower than readState.Index' (duration: 158.844µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:43.206552Z","caller":"traceutil/trace.go:171","msg":"trace[779805801] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"344.560384ms","start":"2026-03-13T23:11:42.861936Z","end":"2026-03-13T23:11:43.206496Z","steps":["trace[779805801] 'process raft request' (duration: 344.42877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:43.206717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:42.861908Z","time spent":"344.727808ms","remote":"127.0.0.1:57006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":896,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:11:43.206808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.140657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-19b2fab0-ecae-4b8b-8d97-481e484db576\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-03-13T23:11:43.206888Z","caller":"traceutil/trace.go:171","msg":"trace[1665157263] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-19b2fab0-ecae-4b8b-8d97-481e484db576; range_end:; response_count:1; response_revision:4819; }","duration":"343.27011ms","start":"2026-03-13T23:11:42.863599Z","end":"2026-03-13T23:11:43.206869Z","steps":["trace[1665157263] 'agreement among raft nodes before linearized reading' (duration: 342.959052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:43.207016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:11:42.863567Z","time spent":"343.430934ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-19b2fab0-ecae-4b8b-8d97-481e484db576\" "} {"level":"info","ts":"2026-03-13T23:11:43.356134Z","caller":"traceutil/trace.go:171","msg":"trace[1379126168] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"141.432467ms","start":"2026-03-13T23:11:43.21463Z","end":"2026-03-13T23:11:43.356062Z","steps":["trace[1379126168] 'process raft request' (duration: 141.037116ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:54.194178Z","caller":"traceutil/trace.go:171","msg":"trace[852703544] linearizableReadLoop","detail":"{readStateIndex:5194; appliedIndex:5193; }","duration":"143.335807ms","start":"2026-03-13T23:11:54.050806Z","end":"2026-03-13T23:11:54.194141Z","steps":["trace[852703544] 'read index received' (duration: 143.0577ms)","trace[852703544] 'applied index is now lower than readState.Index' (duration: 276.357µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:11:54.194388Z","caller":"traceutil/trace.go:171","msg":"trace[1206976649] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"147.446045ms","start":"2026-03-13T23:11:54.046928Z","end":"2026-03-13T23:11:54.194374Z","steps":["trace[1206976649] 'process raft request' (duration: 146.986573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:54.19474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.068132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-13T23:11:54.194771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.929862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-03-13T23:11:54.194833Z","caller":"traceutil/trace.go:171","msg":"trace[912405497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4999; }","duration":"109.225516ms","start":"2026-03-13T23:11:54.085584Z","end":"2026-03-13T23:11:54.194809Z","steps":["trace[912405497] 'agreement among raft nodes before linearized reading' (duration: 108.918237ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:54.194864Z","caller":"traceutil/trace.go:171","msg":"trace[1084480937] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4999; }","duration":"144.073166ms","start":"2026-03-13T23:11:54.050764Z","end":"2026-03-13T23:11:54.194837Z","steps":["trace[1084480937] 'agreement among raft nodes before linearized reading' (duration: 143.794469ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:54.308391Z","caller":"traceutil/trace.go:171","msg":"trace[766134444] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"105.601889ms","start":"2026-03-13T23:11:54.202764Z","end":"2026-03-13T23:11:54.308366Z","steps":["trace[766134444] 'process raft request' (duration: 105.515167ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:54.308629Z","caller":"traceutil/trace.go:171","msg":"trace[998486676] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"106.334579ms","start":"2026-03-13T23:11:54.202273Z","end":"2026-03-13T23:11:54.308607Z","steps":["trace[998486676] 'process raft request' (duration: 105.904757ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:54.308674Z","caller":"traceutil/trace.go:171","msg":"trace[2017380283] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"106.026261ms","start":"2026-03-13T23:11:54.202637Z","end":"2026-03-13T23:11:54.308663Z","steps":["trace[2017380283] 'process raft request' (duration: 105.59279ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:54.308732Z","caller":"traceutil/trace.go:171","msg":"trace[158678642] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"106.613137ms","start":"2026-03-13T23:11:54.202106Z","end":"2026-03-13T23:11:54.308719Z","steps":["trace[158678642] 'process raft request' (duration: 77.348533ms)","trace[158678642] 'compare' (duration: 28.579516ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:13:36.314776Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2743} {"level":"info","ts":"2026-03-13T23:13:36.378832Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2743,"took":"62.362841ms","hash":2500109045,"current-db-size-bytes":42291200,"current-db-size":"42 MB","current-db-size-in-use-bytes":31887360,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-13T23:13:36.378909Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2500109045,"revision":2743,"compact-revision":-1} {"level":"info","ts":"2026-03-13T23:17:36.463817Z","caller":"traceutil/trace.go:171","msg":"trace[1991108224] transaction","detail":"{read_only:false; response_revision:7443; number_of_response:1; }","duration":"383.163653ms","start":"2026-03-13T23:17:36.080617Z","end":"2026-03-13T23:17:36.463781Z","steps":["trace[1991108224] 'process raft request' (duration: 382.492565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:17:36.464018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:17:36.080591Z","time spent":"383.332077ms","remote":"127.0.0.1:57206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:17:47.74553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.224056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:17:47.745642Z","caller":"traceutil/trace.go:171","msg":"trace[1679917174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7493; }","duration":"258.413351ms","start":"2026-03-13T23:17:47.487206Z","end":"2026-03-13T23:17:47.745619Z","steps":["trace[1679917174] 'range keys from in-memory index tree' (duration: 258.114103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:17:47.74594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.302185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-13T23:17:47.745917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.16553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6fn6\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-03-13T23:17:47.746031Z","caller":"traceutil/trace.go:171","msg":"trace[1010553729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7493; }","duration":"303.441779ms","start":"2026-03-13T23:17:47.442567Z","end":"2026-03-13T23:17:47.746009Z","steps":["trace[1010553729] 'range keys from in-memory index tree' (duration: 303.08193ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:17:47.746044Z","caller":"traceutil/trace.go:171","msg":"trace[1591650788] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6fn6; range_end:; response_count:1; response_revision:7493; }","duration":"337.360544ms","start":"2026-03-13T23:17:47.408658Z","end":"2026-03-13T23:17:47.746019Z","steps":["trace[1591650788] 'range keys from in-memory index tree' (duration: 336.906072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:17:47.746073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:17:47.442545Z","time spent":"303.519041ms","remote":"127.0.0.1:57206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-13T23:17:47.746106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:17:47.408628Z","time spent":"337.461827ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-w6fn6\" "} {"level":"info","ts":"2026-03-13T23:17:47.893362Z","caller":"traceutil/trace.go:171","msg":"trace[558105335] transaction","detail":"{read_only:false; response_revision:7494; number_of_response:1; }","duration":"142.592034ms","start":"2026-03-13T23:17:47.750738Z","end":"2026-03-13T23:17:47.89333Z","steps":["trace[558105335] 'process raft request' (duration: 142.422869ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:17:48.650884Z","caller":"traceutil/trace.go:171","msg":"trace[1040938515] transaction","detail":"{read_only:false; response_revision:7498; number_of_response:1; }","duration":"128.461361ms","start":"2026-03-13T23:17:48.522381Z","end":"2026-03-13T23:17:48.650843Z","steps":["trace[1040938515] 'process raft request' (duration: 128.204195ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:17:49.045108Z","caller":"traceutil/trace.go:171","msg":"trace[1480607268] linearizableReadLoop","detail":"{readStateIndex:7781; appliedIndex:7780; }","duration":"394.276317ms","start":"2026-03-13T23:17:48.650808Z","end":"2026-03-13T23:17:49.045085Z","steps":["trace[1480607268] 'read index received' (duration: 331.840139ms)","trace[1480607268] 'applied index is now lower than readState.Index' (duration: 62.435288ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:17:49.045208Z","caller":"traceutil/trace.go:171","msg":"trace[666870804] transaction","detail":"{read_only:false; response_revision:7499; number_of_response:1; }","duration":"483.7964ms","start":"2026-03-13T23:17:48.561364Z","end":"2026-03-13T23:17:49.045161Z","steps":["trace[666870804] 'process raft request' (duration: 421.26914ms)","trace[666870804] 'compare' (duration: 62.084188ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:17:49.045248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.358146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:17:49.045321Z","caller":"traceutil/trace.go:171","msg":"trace[622693807] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:7499; }","duration":"428.48472ms","start":"2026-03-13T23:17:48.616796Z","end":"2026-03-13T23:17:49.04528Z","steps":["trace[622693807] 'agreement among raft nodes before linearized reading' (duration: 428.374047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:17:49.045354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:17:48.56135Z","time spent":"483.936224ms","remote":"127.0.0.1:57206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:17:49.04537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:17:48.616768Z","time spent":"428.590162ms","remote":"127.0.0.1:55112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"info","ts":"2026-03-13T23:17:49.05161Z","caller":"traceutil/trace.go:171","msg":"trace[686082479] transaction","detail":"{read_only:false; response_revision:7500; number_of_response:1; }","duration":"396.185487ms","start":"2026-03-13T23:17:48.655408Z","end":"2026-03-13T23:17:49.051593Z","steps":["trace[686082479] 'process raft request' (duration: 396.077914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:17:49.051732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:17:48.655384Z","time spent":"396.28999ms","remote":"127.0.0.1:57206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:17:49.051751Z","caller":"traceutil/trace.go:171","msg":"trace[1319464979] transaction","detail":"{read_only:false; response_revision:7501; number_of_response:1; }","duration":"331.263434ms","start":"2026-03-13T23:17:48.720465Z","end":"2026-03-13T23:17:49.051728Z","steps":["trace[1319464979] 'process raft request' (duration: 331.097309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:17:49.051833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:17:48.72044Z","time spent":"331.350976ms","remote":"127.0.0.1:57206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:17:49.531836Z","caller":"traceutil/trace.go:171","msg":"trace[372715859] linearizableReadLoop","detail":"{readStateIndex:7788; appliedIndex:7787; }","duration":"101.408327ms","start":"2026-03-13T23:17:49.430397Z","end":"2026-03-13T23:17:49.531806Z","steps":["trace[372715859] 'read index received' (duration: 54.359695ms)","trace[372715859] 'applied index is now lower than readState.Index' (duration: 47.047592ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:17:49.531923Z","caller":"traceutil/trace.go:171","msg":"trace[571845221] transaction","detail":"{read_only:false; response_revision:7505; number_of_response:1; }","duration":"153.335827ms","start":"2026-03-13T23:17:49.378553Z","end":"2026-03-13T23:17:49.531889Z","steps":["trace[571845221] 'process raft request' (duration: 106.239244ms)","trace[571845221] 'compare' (duration: 46.890138ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:17:49.532558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.122885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-03-13T23:17:49.532705Z","caller":"traceutil/trace.go:171","msg":"trace[1934656929] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:7505; }","duration":"102.342411ms","start":"2026-03-13T23:17:49.430332Z","end":"2026-03-13T23:17:49.532674Z","steps":["trace[1934656929] 'agreement among raft nodes before linearized reading' (duration: 101.595931ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:17.218797Z","caller":"traceutil/trace.go:171","msg":"trace[1493955794] transaction","detail":"{read_only:false; response_revision:7690; number_of_response:1; }","duration":"423.460068ms","start":"2026-03-13T23:18:16.795295Z","end":"2026-03-13T23:18:17.218756Z","steps":["trace[1493955794] 'process raft request' (duration: 423.224161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:17.218978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:16.795277Z","time spent":"423.608221ms","remote":"127.0.0.1:57206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:18:18.242963Z","caller":"traceutil/trace.go:171","msg":"trace[1431466315] transaction","detail":"{read_only:false; response_revision:7696; number_of_response:1; }","duration":"154.531659ms","start":"2026-03-13T23:18:18.088393Z","end":"2026-03-13T23:18:18.242925Z","steps":["trace[1431466315] 'process raft request' (duration: 98.88686ms)","trace[1431466315] 'compare' (duration: 55.31211ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:18.243124Z","caller":"traceutil/trace.go:171","msg":"trace[944153792] transaction","detail":"{read_only:false; response_revision:7697; number_of_response:1; }","duration":"113.986798ms","start":"2026-03-13T23:18:18.129105Z","end":"2026-03-13T23:18:18.243091Z","steps":["trace[944153792] 'process raft request' (duration: 113.770693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:29.387498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.796142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-13T23:18:29.387739Z","caller":"traceutil/trace.go:171","msg":"trace[746210258] transaction","detail":"{read_only:false; response_revision:7786; number_of_response:1; }","duration":"134.218402ms","start":"2026-03-13T23:18:29.253504Z","end":"2026-03-13T23:18:29.387723Z","steps":["trace[746210258] 'process raft request' (duration: 134.169031ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:29.387791Z","caller":"traceutil/trace.go:171","msg":"trace[1232807175] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7784; }","duration":"373.185861ms","start":"2026-03-13T23:18:29.014593Z","end":"2026-03-13T23:18:29.387779Z","steps":["trace[1232807175] 'process raft request' (duration: 141.986658ms)","trace[1232807175] 'compare' (duration: 230.677749ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:29.387783Z","caller":"traceutil/trace.go:171","msg":"trace[1013193285] transaction","detail":"{read_only:false; response_revision:7785; number_of_response:1; }","duration":"373.1625ms","start":"2026-03-13T23:18:29.014591Z","end":"2026-03-13T23:18:29.387754Z","steps":["trace[1013193285] 'process raft request' (duration: 373.007396ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:29.387814Z","caller":"traceutil/trace.go:171","msg":"trace[2101263512] linearizableReadLoop","detail":"{readStateIndex:8079; appliedIndex:8077; }","duration":"141.967918ms","start":"2026-03-13T23:18:29.24576Z","end":"2026-03-13T23:18:29.387728Z","steps":["trace[2101263512] 'read index received' (duration: 37.141µs)","trace[2101263512] 'applied index is now lower than readState.Index' (duration: 141.927986ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:18:29.387833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:29.014576Z","time spent":"373.234812ms","remote":"127.0.0.1:57032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-03-13T23:18:29.38816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.392338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-r6mpv\" ","response":"range_response_count:1 size:4666"} {"level":"info","ts":"2026-03-13T23:18:29.388233Z","caller":"traceutil/trace.go:171","msg":"trace[1989439796] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-r6mpv; range_end:; response_count:1; response_revision:7786; }","duration":"142.491531ms","start":"2026-03-13T23:18:29.245721Z","end":"2026-03-13T23:18:29.388212Z","steps":["trace[1989439796] 'agreement among raft nodes before linearized reading' (duration: 142.316077ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:29.389006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:18:29.014576Z","time spent":"373.296373ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4651,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:18:30.248728Z","caller":"traceutil/trace.go:171","msg":"trace[26416620] transaction","detail":"{read_only:false; response_revision:7796; number_of_response:1; }","duration":"237.635183ms","start":"2026-03-13T23:18:30.011073Z","end":"2026-03-13T23:18:30.248708Z","steps":["trace[26416620] 'process raft request' (duration: 237.483759ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:30.367412Z","caller":"traceutil/trace.go:171","msg":"trace[692965870] linearizableReadLoop","detail":"{readStateIndex:8092; appliedIndex:8091; }","duration":"110.116577ms","start":"2026-03-13T23:18:30.257276Z","end":"2026-03-13T23:18:30.367393Z","steps":["trace[692965870] 'read index received' (duration: 93.849337ms)","trace[692965870] 'applied index is now lower than readState.Index' (duration: 16.26645ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:30.367431Z","caller":"traceutil/trace.go:171","msg":"trace[2100921792] transaction","detail":"{read_only:false; response_revision:7797; number_of_response:1; }","duration":"294.028981ms","start":"2026-03-13T23:18:30.073362Z","end":"2026-03-13T23:18:30.367391Z","steps":["trace[2100921792] 'process raft request' (duration: 277.814653ms)","trace[2100921792] 'compare' (duration: 16.075275ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:18:30.367676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.379553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-r6mpv\" ","response":"range_response_count:1 size:4651"} {"level":"warn","ts":"2026-03-13T23:18:30.36772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.249375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-03-13T23:18:30.367737Z","caller":"traceutil/trace.go:171","msg":"trace[1112280146] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-r6mpv; range_end:; response_count:1; response_revision:7797; }","duration":"110.472566ms","start":"2026-03-13T23:18:30.257248Z","end":"2026-03-13T23:18:30.367721Z","steps":["trace[1112280146] 'agreement among raft nodes before linearized reading' (duration: 110.25552ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:30.367754Z","caller":"traceutil/trace.go:171","msg":"trace[1354067057] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:7799; }","duration":"106.336937ms","start":"2026-03-13T23:18:30.261408Z","end":"2026-03-13T23:18:30.367745Z","steps":["trace[1354067057] 'agreement among raft nodes before linearized reading' (duration: 106.207803ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:30.368102Z","caller":"traceutil/trace.go:171","msg":"trace[396173518] transaction","detail":"{read_only:false; response_revision:7798; number_of_response:1; }","duration":"110.326512ms","start":"2026-03-13T23:18:30.257763Z","end":"2026-03-13T23:18:30.368089Z","steps":["trace[396173518] 'process raft request' (duration: 109.605313ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:31.360508Z","caller":"traceutil/trace.go:171","msg":"trace[1636269137] linearizableReadLoop","detail":"{readStateIndex:8100; appliedIndex:8099; }","duration":"102.259099ms","start":"2026-03-13T23:18:31.258224Z","end":"2026-03-13T23:18:31.360483Z","steps":["trace[1636269137] 'read index received' (duration: 101.112139ms)","trace[1636269137] 'applied index is now lower than readState.Index' (duration: 1.14581ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:18:31.360603Z","caller":"traceutil/trace.go:171","msg":"trace[78367746] transaction","detail":"{read_only:false; response_revision:7805; number_of_response:1; }","duration":"136.136873ms","start":"2026-03-13T23:18:31.224431Z","end":"2026-03-13T23:18:31.360568Z","steps":["trace[78367746] 'process raft request' (duration: 135.277871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:18:31.360718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.479325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-03-13T23:18:31.36167Z","caller":"traceutil/trace.go:171","msg":"trace[833976164] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7805; }","duration":"103.486122ms","start":"2026-03-13T23:18:31.258169Z","end":"2026-03-13T23:18:31.361655Z","steps":["trace[833976164] 'agreement among raft nodes before linearized reading' (duration: 102.410864ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:18:36.321899Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5762} {"level":"info","ts":"2026-03-13T23:18:36.422652Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5762,"took":"98.362747ms","hash":3724130242,"current-db-size-bytes":43749376,"current-db-size":"44 MB","current-db-size-in-use-bytes":23568384,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-13T23:18:36.422713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3724130242,"revision":5762,"compact-revision":2743} {"level":"info","ts":"2026-03-13T23:22:20.27848Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4ec288cbdba84d36","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-13T23:22:20.284835Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-13T23:22:20.284915Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-13T23:23:36.328556Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7850} {"level":"info","ts":"2026-03-13T23:23:36.391076Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7850,"took":"60.711213ms","hash":2939771742,"current-db-size-bytes":43749376,"current-db-size":"44 MB","current-db-size-in-use-bytes":25751552,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-13T23:23:36.391136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2939771742,"revision":7850,"compact-revision":5762} {"level":"info","ts":"2026-03-13T23:28:36.334066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10015} {"level":"info","ts":"2026-03-13T23:28:36.410968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10015,"took":"75.081362ms","hash":3949755278,"current-db-size-bytes":43749376,"current-db-size":"44 MB","current-db-size-in-use-bytes":16666624,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-13T23:28:36.411049Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3949755278,"revision":10015,"compact-revision":7850}