{"level":"warn","ts":"2026-04-02T08:48:57.035916Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T08:48:57.036083Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-04-02T08:48:57.036175Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T08:48:57.036189Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-02T08:48:57.036209Z","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-04-02T08:48:57.036938Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-02T08:48:57.037022Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-04-02T08:48:57.041537Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.063471ms"} {"level":"info","ts":"2026-04-02T08:48:57.048117Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-02T08:48:57.048263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T08:48:57.048299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-02T08:48:57.048315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T08:48:57.048331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-02T08:48:57.048427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-02T08:48:57.05437Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T08:48:57.055911Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T08:48:57.057389Z","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-04-02T08:48:57.060995Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T08:48:57.061454Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T08:48:57.061843Z","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-04-02T08:48:57.062296Z","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-04-02T08:48:57.062876Z","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-04-02T08:48:57.063756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-02T08:48:57.064069Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-02T08:48:57.065739Z","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-04-02T08:48:57.065811Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-02T08:48:57.065869Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-02T08:48:57.065982Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T08:48:57.066016Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T08:48:57.74976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T08:48:57.749863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T08:48:57.749892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-02T08:48:57.749917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-02T08:48:57.74993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-02T08:48:57.749948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-02T08:48:57.749963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-02T08:48:57.751097Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:48:57.752205Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T08:48:57.752247Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T08:48:57.752368Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T08:48:57.752699Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T08:48:57.752768Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T08:48:57.752827Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:48:57.752939Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:48:57.752974Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:48:57.75603Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-04-02T08:48:57.757777Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T08:49:00.207924Z","caller":"traceutil/trace.go:171","msg":"trace[231613303] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"131.943687ms","start":"2026-04-02T08:49:00.07595Z","end":"2026-04-02T08:49:00.207893Z","steps":["trace[231613303] 'read index received' (duration: 74.059256ms)","trace[231613303] 'applied index is now lower than readState.Index' (duration: 57.883611ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:49:00.20805Z","caller":"traceutil/trace.go:171","msg":"trace[1877350827] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"198.584797ms","start":"2026-04-02T08:49:00.009422Z","end":"2026-04-02T08:49:00.208007Z","steps":["trace[1877350827] 'process raft request' (duration: 140.585783ms)","trace[1877350827] 'compare' (duration: 57.757476ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:49:00.208186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.169403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T08:49:00.208272Z","caller":"traceutil/trace.go:171","msg":"trace[1798868459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"132.375668ms","start":"2026-04-02T08:49:00.075881Z","end":"2026-04-02T08:49:00.208257Z","steps":["trace[1798868459] 'agreement among raft nodes before linearized reading' (duration: 132.170942ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:49:18.438811Z","caller":"traceutil/trace.go:171","msg":"trace[200655281] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"107.690109ms","start":"2026-04-02T08:49:18.331076Z","end":"2026-04-02T08:49:18.438766Z","steps":["trace[200655281] 'process raft request' (duration: 107.524104ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:49:40.109651Z","caller":"traceutil/trace.go:171","msg":"trace[539418892] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"163.565724ms","start":"2026-04-02T08:49:39.946059Z","end":"2026-04-02T08:49:40.109624Z","steps":["trace[539418892] 'process raft request' (duration: 162.9903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:40.188096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.894073ms","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-04-02T08:49:40.188174Z","caller":"traceutil/trace.go:171","msg":"trace[621068241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"125.997556ms","start":"2026-04-02T08:49:40.06216Z","end":"2026-04-02T08:49:40.188158Z","steps":["trace[621068241] 'agreement among raft nodes before linearized reading' (duration: 125.77728ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:49:40.22795Z","caller":"traceutil/trace.go:171","msg":"trace[1371894319] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"103.008149ms","start":"2026-04-02T08:49:40.124922Z","end":"2026-04-02T08:49:40.22793Z","steps":["trace[1371894319] 'process raft request' (duration: 102.894136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:40.866791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.027315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:49:40.86713Z","caller":"traceutil/trace.go:171","msg":"trace[1685388061] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"675.900195ms","start":"2026-04-02T08:49:40.191207Z","end":"2026-04-02T08:49:40.867107Z","steps":["trace[1685388061] 'process raft request' (duration: 675.756761ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:49:40.867191Z","caller":"traceutil/trace.go:171","msg":"trace[1013432466] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"702.511637ms","start":"2026-04-02T08:49:40.164641Z","end":"2026-04-02T08:49:40.867152Z","steps":["trace[1013432466] 'process raft request' (duration: 225.678431ms)","trace[1013432466] 'compare' (duration: 475.862271ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:49:40.867256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.765995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:49:40.867301Z","caller":"traceutil/trace.go:171","msg":"trace[436626953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"344.869618ms","start":"2026-04-02T08:49:40.522421Z","end":"2026-04-02T08:49:40.867291Z","steps":["trace[436626953] 'agreement among raft nodes before linearized reading' (duration: 344.791956ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:49:40.867132Z","caller":"traceutil/trace.go:171","msg":"trace[566614048] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:609; }","duration":"344.56993ms","start":"2026-04-02T08:49:40.522488Z","end":"2026-04-02T08:49:40.867058Z","steps":["trace[566614048] 'read index received' (duration: 44.061µs)","trace[566614048] 'applied index is now lower than readState.Index' (duration: 344.524529ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:49:40.867352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:49:40.522396Z","time spent":"344.939269ms","remote":"127.0.0.1:33888","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-04-02T08:49:40.867317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:49:40.164609Z","time spent":"702.651809ms","remote":"127.0.0.1:34390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:49:40.867484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.017713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-02T08:49:40.867543Z","caller":"traceutil/trace.go:171","msg":"trace[1659127665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"321.100906ms","start":"2026-04-02T08:49:40.546421Z","end":"2026-04-02T08:49:40.867522Z","steps":["trace[1659127665] 'agreement among raft nodes before linearized reading' (duration: 321.005203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:40.867586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:49:40.546408Z","time spent":"321.168037ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:49:40.867712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.594593ms","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-04-02T08:49:40.867792Z","caller":"traceutil/trace.go:171","msg":"trace[1868305467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"235.701806ms","start":"2026-04-02T08:49:40.63208Z","end":"2026-04-02T08:49:40.867782Z","steps":["trace[1868305467] 'agreement among raft nodes before linearized reading' (duration: 235.475211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:40.867951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:49:40.191182Z","time spent":"676.0442ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:49:55.056115Z","caller":"traceutil/trace.go:171","msg":"trace[361466478] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"121.300967ms","start":"2026-04-02T08:49:54.93463Z","end":"2026-04-02T08:49:55.055931Z","steps":["trace[361466478] 'process raft request' (duration: 121.038661ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:49:55.160683Z","caller":"traceutil/trace.go:171","msg":"trace[15342692] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"224.792879ms","start":"2026-04-02T08:49:54.935865Z","end":"2026-04-02T08:49:55.160658Z","steps":["trace[15342692] 'process raft request' (duration: 219.629673ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:49:55.160877Z","caller":"traceutil/trace.go:171","msg":"trace[1826103840] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"223.630032ms","start":"2026-04-02T08:49:54.937213Z","end":"2026-04-02T08:49:55.160843Z","steps":["trace[1826103840] 'process raft request' (duration: 223.413716ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:51:09.639326Z","caller":"traceutil/trace.go:171","msg":"trace[782502001] linearizableReadLoop","detail":"{readStateIndex:1879; appliedIndex:1878; }","duration":"132.911262ms","start":"2026-04-02T08:51:09.506389Z","end":"2026-04-02T08:51:09.6393Z","steps":["trace[782502001] 'read index received' (duration: 132.517774ms)","trace[782502001] 'applied index is now lower than readState.Index' (duration: 392.238µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:51:09.639449Z","caller":"traceutil/trace.go:171","msg":"trace[333628047] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"142.682246ms","start":"2026-04-02T08:51:09.496758Z","end":"2026-04-02T08:51:09.63944Z","steps":["trace[333628047] 'process raft request' (duration: 142.213356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:51:09.639611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.123877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:51:09.639678Z","caller":"traceutil/trace.go:171","msg":"trace[772936834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1811; }","duration":"116.23601ms","start":"2026-04-02T08:51:09.523427Z","end":"2026-04-02T08:51:09.639663Z","steps":["trace[772936834] 'agreement among raft nodes before linearized reading' (duration: 116.124298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:51:09.639676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.828785ms","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-04-02T08:51:09.639759Z","caller":"traceutil/trace.go:171","msg":"trace[1956349163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1811; }","duration":"128.953407ms","start":"2026-04-02T08:51:09.510787Z","end":"2026-04-02T08:51:09.639741Z","steps":["trace[1956349163] 'agreement among raft nodes before linearized reading' (duration: 128.701571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:51:09.639697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.282681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-02T08:51:09.639837Z","caller":"traceutil/trace.go:171","msg":"trace[1071078953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1811; }","duration":"133.467154ms","start":"2026-04-02T08:51:09.506349Z","end":"2026-04-02T08:51:09.639816Z","steps":["trace[1071078953] 'agreement among raft nodes before linearized reading' (duration: 133.209289ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:51:24.216752Z","caller":"traceutil/trace.go:171","msg":"trace[1771587872] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"110.564907ms","start":"2026-04-02T08:51:24.106167Z","end":"2026-04-02T08:51:24.216732Z","steps":["trace[1771587872] 'process raft request' (duration: 110.412424ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:51:58.102112Z","caller":"traceutil/trace.go:171","msg":"trace[616737025] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2200; }","duration":"226.185947ms","start":"2026-04-02T08:51:57.875899Z","end":"2026-04-02T08:51:58.102085Z","steps":["trace[616737025] 'read index received' (duration: 193.244746ms)","trace[616737025] 'applied index is now lower than readState.Index' (duration: 32.940271ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:51:58.102163Z","caller":"traceutil/trace.go:171","msg":"trace[1241154772] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"233.867302ms","start":"2026-04-02T08:51:57.868261Z","end":"2026-04-02T08:51:58.102128Z","steps":["trace[1241154772] 'process raft request' (duration: 200.89825ms)","trace[1241154772] 'compare' (duration: 32.828269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:51:58.102489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.572425ms","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-04-02T08:51:58.102577Z","caller":"traceutil/trace.go:171","msg":"trace[1132182869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2123; }","duration":"226.715919ms","start":"2026-04-02T08:51:57.875845Z","end":"2026-04-02T08:51:58.102561Z","steps":["trace[1132182869] 'agreement among raft nodes before linearized reading' (duration: 226.383141ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:51:58.162496Z","caller":"traceutil/trace.go:171","msg":"trace[853203595] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"111.593544ms","start":"2026-04-02T08:51:58.050882Z","end":"2026-04-02T08:51:58.162475Z","steps":["trace[853203595] 'process raft request' (duration: 111.500401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:51:58.162634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.354601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T08:51:58.162674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.542666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-02T08:51:58.162696Z","caller":"traceutil/trace.go:171","msg":"trace[266849899] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2124; }","duration":"269.438944ms","start":"2026-04-02T08:51:57.893243Z","end":"2026-04-02T08:51:58.162682Z","steps":["trace[266849899] 'agreement among raft nodes before linearized reading' (duration: 269.330552ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:51:58.162734Z","caller":"traceutil/trace.go:171","msg":"trace[1619784060] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2124; }","duration":"269.625398ms","start":"2026-04-02T08:51:57.893091Z","end":"2026-04-02T08:51:58.162716Z","steps":["trace[1619784060] 'agreement among raft nodes before linearized reading' (duration: 269.443224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:51:58.416583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.984645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:51:58.416847Z","caller":"traceutil/trace.go:171","msg":"trace[848108400] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"310.516479ms","start":"2026-04-02T08:51:58.106288Z","end":"2026-04-02T08:51:58.416804Z","steps":["trace[848108400] 'process raft request' (duration: 133.214267ms)","trace[848108400] 'compare' (duration: 176.802611ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:51:58.416971Z","caller":"traceutil/trace.go:171","msg":"trace[257036495] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"250.399789ms","start":"2026-04-02T08:51:58.166555Z","end":"2026-04-02T08:51:58.416955Z","steps":["trace[257036495] 'process raft request' (duration: 250.170854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:51:58.417004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:51:58.10627Z","time spent":"310.626282ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:53:12.62392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.618726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:53:12.624101Z","caller":"traceutil/trace.go:171","msg":"trace[1151809988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2723; }","duration":"100.81057ms","start":"2026-04-02T08:53:12.523262Z","end":"2026-04-02T08:53:12.624073Z","steps":["trace[1151809988] 'range keys from in-memory index tree' (duration: 100.530704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:53:13.136001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.730738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:53:13.136204Z","caller":"traceutil/trace.go:171","msg":"trace[369541468] linearizableReadLoop","detail":"{readStateIndex:2829; appliedIndex:2827; }","duration":"126.519982ms","start":"2026-04-02T08:53:13.009668Z","end":"2026-04-02T08:53:13.136188Z","steps":["trace[369541468] 'read index received' (duration: 92.660659ms)","trace[369541468] 'applied index is now lower than readState.Index' (duration: 33.858383ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:53:13.136345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.677966ms","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-04-02T08:53:13.136362Z","caller":"traceutil/trace.go:171","msg":"trace[1700956723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2726; }","duration":"126.738967ms","start":"2026-04-02T08:53:13.009618Z","end":"2026-04-02T08:53:13.136357Z","steps":["trace[1700956723] 'agreement among raft nodes before linearized reading' (duration: 126.617514ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:53:13.136351Z","caller":"traceutil/trace.go:171","msg":"trace[1661237036] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"309.268983ms","start":"2026-04-02T08:53:12.827052Z","end":"2026-04-02T08:53:13.136322Z","steps":["trace[1661237036] 'process raft request' (duration: 100.128995ms)","trace[1661237036] 'compare' (duration: 208.510244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:53:13.136478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:53:12.827035Z","time spent":"309.376425ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:53:13.136539Z","caller":"traceutil/trace.go:171","msg":"trace[1355088334] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"309.425667ms","start":"2026-04-02T08:53:12.82709Z","end":"2026-04-02T08:53:13.136516Z","steps":["trace[1355088334] 'process raft request' (duration: 309.007237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:53:13.13667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:53:12.827062Z","time spent":"309.542369ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:55:39.856079Z","caller":"traceutil/trace.go:171","msg":"trace[1153419107] transaction","detail":"{read_only:false; response_revision:3696; number_of_response:1; }","duration":"302.631232ms","start":"2026-04-02T08:55:39.553383Z","end":"2026-04-02T08:55:39.856014Z","steps":["trace[1153419107] 'process raft request' (duration: 302.405917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:55:39.856277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:55:39.553361Z","time spent":"302.826776ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:55:40.154463Z","caller":"traceutil/trace.go:171","msg":"trace[1913865786] transaction","detail":"{read_only:false; response_revision:3697; number_of_response:1; }","duration":"294.957694ms","start":"2026-04-02T08:55:39.859478Z","end":"2026-04-02T08:55:40.154435Z","steps":["trace[1913865786] 'process raft request' (duration: 294.718729ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:55:40.184338Z","caller":"traceutil/trace.go:171","msg":"trace[1476773732] transaction","detail":"{read_only:false; response_revision:3698; number_of_response:1; }","duration":"324.791913ms","start":"2026-04-02T08:55:39.859526Z","end":"2026-04-02T08:55:40.184317Z","steps":["trace[1476773732] 'process raft request' (duration: 324.625469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:55:40.184493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:55:39.859509Z","time spent":"324.898176ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:55:40.184561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.315154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-02T08:55:40.184659Z","caller":"traceutil/trace.go:171","msg":"trace[1603407418] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3698; }","duration":"282.460246ms","start":"2026-04-02T08:55:39.902181Z","end":"2026-04-02T08:55:40.184641Z","steps":["trace[1603407418] 'agreement among raft nodes before linearized reading' (duration: 282.228021ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:55:40.184833Z","caller":"traceutil/trace.go:171","msg":"trace[554968221] linearizableReadLoop","detail":"{readStateIndex:3836; appliedIndex:3834; }","duration":"282.040747ms","start":"2026-04-02T08:55:39.902233Z","end":"2026-04-02T08:55:40.184274Z","steps":["trace[554968221] 'read index received' (duration: 252.091105ms)","trace[554968221] 'applied index is now lower than readState.Index' (duration: 29.948802ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:55:48.909706Z","caller":"traceutil/trace.go:171","msg":"trace[1902058811] transaction","detail":"{read_only:false; response_revision:3749; number_of_response:1; }","duration":"135.594199ms","start":"2026-04-02T08:55:48.774076Z","end":"2026-04-02T08:55:48.90967Z","steps":["trace[1902058811] 'process raft request' (duration: 97.936339ms)","trace[1902058811] 'compare' (duration: 37.461865ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:56:10.097871Z","caller":"traceutil/trace.go:171","msg":"trace[1632714750] transaction","detail":"{read_only:false; response_revision:3885; number_of_response:1; }","duration":"118.309558ms","start":"2026-04-02T08:56:09.979517Z","end":"2026-04-02T08:56:10.097826Z","steps":["trace[1632714750] 'process raft request' (duration: 118.091883ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:10.09786Z","caller":"traceutil/trace.go:171","msg":"trace[1925168944] transaction","detail":"{read_only:false; response_revision:3884; number_of_response:1; }","duration":"123.305896ms","start":"2026-04-02T08:56:09.97451Z","end":"2026-04-02T08:56:10.097815Z","steps":["trace[1925168944] 'process raft request' (duration: 96.242476ms)","trace[1925168944] 'compare' (duration: 26.621539ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:56:10.441825Z","caller":"traceutil/trace.go:171","msg":"trace[335284662] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3900; }","duration":"141.693131ms","start":"2026-04-02T08:56:10.300082Z","end":"2026-04-02T08:56:10.441775Z","steps":["trace[335284662] 'process raft request' (duration: 100.104647ms)","trace[335284662] 'compare' (duration: 41.502282ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:56:13.064062Z","caller":"traceutil/trace.go:171","msg":"trace[1389662242] linearizableReadLoop","detail":"{readStateIndex:4070; appliedIndex:4069; }","duration":"124.588135ms","start":"2026-04-02T08:56:12.939443Z","end":"2026-04-02T08:56:13.064031Z","steps":["trace[1389662242] 'read index received' (duration: 78.363183ms)","trace[1389662242] 'applied index is now lower than readState.Index' (duration: 46.223742ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:56:13.064089Z","caller":"traceutil/trace.go:171","msg":"trace[544563554] transaction","detail":"{read_only:false; response_revision:3923; number_of_response:1; }","duration":"125.526768ms","start":"2026-04-02T08:56:12.938537Z","end":"2026-04-02T08:56:13.064064Z","steps":["trace[544563554] 'process raft request' (duration: 79.295356ms)","trace[544563554] 'compare' (duration: 45.983836ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:56:13.064353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.881192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:56:13.064505Z","caller":"traceutil/trace.go:171","msg":"trace[1773435220] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com; range_end:; response_count:0; response_revision:3923; }","duration":"125.015755ms","start":"2026-04-02T08:56:12.939399Z","end":"2026-04-02T08:56:13.064415Z","steps":["trace[1773435220] 'agreement among raft nodes before linearized reading' (duration: 124.712358ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:13.234444Z","caller":"traceutil/trace.go:171","msg":"trace[257316850] transaction","detail":"{read_only:false; response_revision:3926; number_of_response:1; }","duration":"102.460503ms","start":"2026-04-02T08:56:13.131955Z","end":"2026-04-02T08:56:13.234415Z","steps":["trace[257316850] 'process raft request' (duration: 60.388469ms)","trace[257316850] 'compare' (duration: 41.663135ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:56:13.427254Z","caller":"traceutil/trace.go:171","msg":"trace[1247588345] transaction","detail":"{read_only:false; response_revision:3930; number_of_response:1; }","duration":"118.626056ms","start":"2026-04-02T08:56:13.308585Z","end":"2026-04-02T08:56:13.427211Z","steps":["trace[1247588345] 'process raft request' (duration: 69.113765ms)","trace[1247588345] 'compare' (duration: 49.261625ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:56:13.435156Z","caller":"traceutil/trace.go:171","msg":"trace[1758595494] transaction","detail":"{read_only:false; response_revision:3931; number_of_response:1; }","duration":"116.214499ms","start":"2026-04-02T08:56:13.318913Z","end":"2026-04-02T08:56:13.435128Z","steps":["trace[1758595494] 'process raft request' (duration: 115.925711ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:13.961659Z","caller":"traceutil/trace.go:171","msg":"trace[1416852759] transaction","detail":"{read_only:false; response_revision:3940; number_of_response:1; }","duration":"152.406405ms","start":"2026-04-02T08:56:13.809223Z","end":"2026-04-02T08:56:13.961629Z","steps":["trace[1416852759] 'process raft request' (duration: 65.058629ms)","trace[1416852759] 'compare' (duration: 86.975506ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:56:14.176538Z","caller":"traceutil/trace.go:171","msg":"trace[127789323] transaction","detail":"{read_only:false; response_revision:3942; number_of_response:1; }","duration":"136.19816ms","start":"2026-04-02T08:56:14.04031Z","end":"2026-04-02T08:56:14.176508Z","steps":["trace[127789323] 'process raft request' (duration: 70.22037ms)","trace[127789323] 'compare' (duration: 65.806746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:56:55.907446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.950682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:56:55.907526Z","caller":"traceutil/trace.go:171","msg":"trace[2097097352] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4545; }","duration":"201.082135ms","start":"2026-04-02T08:56:55.706429Z","end":"2026-04-02T08:56:55.907511Z","steps":["trace[2097097352] 'range keys from in-memory index tree' (duration: 200.88091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:55.90768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.546332ms","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-04-02T08:56:55.907762Z","caller":"traceutil/trace.go:171","msg":"trace[563614026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4545; }","duration":"197.690595ms","start":"2026-04-02T08:56:55.710051Z","end":"2026-04-02T08:56:55.907741Z","steps":["trace[563614026] 'range keys from in-memory index tree' (duration: 197.314587ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:57.317732Z","caller":"traceutil/trace.go:171","msg":"trace[107617099] transaction","detail":"{read_only:false; response_revision:4615; number_of_response:1; }","duration":"143.80196ms","start":"2026-04-02T08:56:57.173899Z","end":"2026-04-02T08:56:57.317701Z","steps":["trace[107617099] 'process raft request' (duration: 143.623866ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:57.346004Z","caller":"traceutil/trace.go:171","msg":"trace[1326261210] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"113.066464ms","start":"2026-04-02T08:56:57.232912Z","end":"2026-04-02T08:56:57.345979Z","steps":["trace[1326261210] 'process raft request' (duration: 106.341195ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:59.425135Z","caller":"traceutil/trace.go:171","msg":"trace[622406334] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"175.92487ms","start":"2026-04-02T08:56:59.249172Z","end":"2026-04-02T08:56:59.425097Z","steps":["trace[622406334] 'process raft request' (duration: 141.696499ms)","trace[622406334] 'compare' (duration: 34.101127ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:56:59.688371Z","caller":"traceutil/trace.go:171","msg":"trace[1050542051] transaction","detail":"{read_only:false; response_revision:4646; number_of_response:1; }","duration":"398.024541ms","start":"2026-04-02T08:56:59.290315Z","end":"2026-04-02T08:56:59.688339Z","steps":["trace[1050542051] 'process raft request' (duration: 378.853869ms)","trace[1050542051] 'compare' (duration: 18.986527ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:56:59.688558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:59.290294Z","time spent":"398.170706ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1249,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:56:59.688692Z","caller":"traceutil/trace.go:171","msg":"trace[2026084140] linearizableReadLoop","detail":"{readStateIndex:4821; appliedIndex:4817; }","duration":"314.373504ms","start":"2026-04-02T08:56:59.374287Z","end":"2026-04-02T08:56:59.68866Z","steps":["trace[2026084140] 'read index received' (duration: 16.655554ms)","trace[2026084140] 'applied index is now lower than readState.Index' (duration: 297.7171ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:56:59.688788Z","caller":"traceutil/trace.go:171","msg":"trace[557965242] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"397.52487ms","start":"2026-04-02T08:56:59.291232Z","end":"2026-04-02T08:56:59.688757Z","steps":["trace[557965242] 'process raft request' (duration: 397.049637ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:59.688815Z","caller":"traceutil/trace.go:171","msg":"trace[2014420307] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"337.084351ms","start":"2026-04-02T08:56:59.351669Z","end":"2026-04-02T08:56:59.688754Z","steps":["trace[2014420307] 'process raft request' (duration: 336.874847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:59.688938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:59.29121Z","time spent":"397.651122ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:56:59.68895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.647441ms","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-04-02T08:56:59.689023Z","caller":"traceutil/trace.go:171","msg":"trace[1065786497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4648; }","duration":"314.726112ms","start":"2026-04-02T08:56:59.37428Z","end":"2026-04-02T08:56:59.689006Z","steps":["trace[1065786497] 'agreement among raft nodes before linearized reading' (duration: 314.515647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:59.689071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:59.37422Z","time spent":"314.843305ms","remote":"127.0.0.1:34138","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-04-02T08:56:59.68957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:59.351653Z","time spent":"337.265675ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:56:59.816239Z","caller":"traceutil/trace.go:171","msg":"trace[306858375] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"122.869015ms","start":"2026-04-02T08:56:59.69335Z","end":"2026-04-02T08:56:59.816219Z","steps":["trace[306858375] 'process raft request' (duration: 122.831664ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:59.816283Z","caller":"traceutil/trace.go:171","msg":"trace[431382325] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"362.02073ms","start":"2026-04-02T08:56:59.454229Z","end":"2026-04-02T08:56:59.81625Z","steps":["trace[431382325] 'process raft request' (duration: 361.866036ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:59.816404Z","caller":"traceutil/trace.go:171","msg":"trace[1550579584] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"386.986781ms","start":"2026-04-02T08:56:59.429391Z","end":"2026-04-02T08:56:59.816378Z","steps":["trace[1550579584] 'process raft request' (duration: 373.856571ms)","trace[1550579584] 'compare' (duration: 12.624698ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:56:59.816445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:59.454218Z","time spent":"362.134872ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:56:59.816551Z","caller":"traceutil/trace.go:171","msg":"trace[1060949126] linearizableReadLoop","detail":"{readStateIndex:4823; appliedIndex:4821; }","duration":"127.771771ms","start":"2026-04-02T08:56:59.688768Z","end":"2026-04-02T08:56:59.81654Z","steps":["trace[1060949126] 'read index received' (duration: 114.460166ms)","trace[1060949126] 'applied index is now lower than readState.Index' (duration: 13.310285ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:56:59.816574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:59.429364Z","time spent":"387.116513ms","remote":"127.0.0.1:33964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":708,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:56:59.816735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.639293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1261"} {"level":"info","ts":"2026-04-02T08:56:59.816765Z","caller":"traceutil/trace.go:171","msg":"trace[1843559109] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4651; }","duration":"378.719095ms","start":"2026-04-02T08:56:59.438038Z","end":"2026-04-02T08:56:59.816757Z","steps":["trace[1843559109] 'agreement among raft nodes before linearized reading' (duration: 378.574991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:59.816791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:59.438021Z","time spent":"378.763795ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1284,"request content":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-04-02T08:57:03.793375Z","caller":"traceutil/trace.go:171","msg":"trace[868534772] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"105.247759ms","start":"2026-04-02T08:57:03.688089Z","end":"2026-04-02T08:57:03.793337Z","steps":["trace[868534772] 'process raft request' (duration: 101.425628ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:03.793491Z","caller":"traceutil/trace.go:171","msg":"trace[542739223] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"105.214558ms","start":"2026-04-02T08:57:03.688253Z","end":"2026-04-02T08:57:03.793468Z","steps":["trace[542739223] 'process raft request' (duration: 104.960352ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:03.937578Z","caller":"traceutil/trace.go:171","msg":"trace[213188587] linearizableReadLoop","detail":"{readStateIndex:4923; appliedIndex:4920; }","duration":"110.79715ms","start":"2026-04-02T08:57:03.82676Z","end":"2026-04-02T08:57:03.937557Z","steps":["trace[213188587] 'read index received' (duration: 52.910282ms)","trace[213188587] 'applied index is now lower than readState.Index' (duration: 57.885938ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:57:03.937823Z","caller":"traceutil/trace.go:171","msg":"trace[1885863560] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"111.771072ms","start":"2026-04-02T08:57:03.826029Z","end":"2026-04-02T08:57:03.9378Z","steps":["trace[1885863560] 'process raft request' (duration: 111.460215ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:03.937904Z","caller":"traceutil/trace.go:171","msg":"trace[1489507590] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"134.017728ms","start":"2026-04-02T08:57:03.803851Z","end":"2026-04-02T08:57:03.937869Z","steps":["trace[1489507590] 'process raft request' (duration: 122.403233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:57:03.937978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.203509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-02T08:57:03.938109Z","caller":"traceutil/trace.go:171","msg":"trace[1213484431] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4747; }","duration":"111.368274ms","start":"2026-04-02T08:57:03.826711Z","end":"2026-04-02T08:57:03.938079Z","steps":["trace[1213484431] 'agreement among raft nodes before linearized reading' (duration: 111.160538ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:04.029457Z","caller":"traceutil/trace.go:171","msg":"trace[492087221] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"143.881672ms","start":"2026-04-02T08:57:03.885547Z","end":"2026-04-02T08:57:04.029429Z","steps":["trace[492087221] 'process raft request' (duration: 143.689658ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:04.029861Z","caller":"traceutil/trace.go:171","msg":"trace[2038995007] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"144.142168ms","start":"2026-04-02T08:57:03.885705Z","end":"2026-04-02T08:57:04.029847Z","steps":["trace[2038995007] 'process raft request' (duration: 143.667217ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:04.029832Z","caller":"traceutil/trace.go:171","msg":"trace[2010672642] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"143.522453ms","start":"2026-04-02T08:57:03.88628Z","end":"2026-04-02T08:57:04.029802Z","steps":["trace[2010672642] 'process raft request' (duration: 143.354399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:57:04.029946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.009101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T08:57:04.030049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.842632ms","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-04-02T08:57:04.030075Z","caller":"traceutil/trace.go:171","msg":"trace[282015136] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:4750; }","duration":"132.166825ms","start":"2026-04-02T08:57:03.897864Z","end":"2026-04-02T08:57:04.030031Z","steps":["trace[282015136] 'agreement among raft nodes before linearized reading' (duration: 131.94911ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:04.030119Z","caller":"traceutil/trace.go:171","msg":"trace[535605192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4750; }","duration":"198.939314ms","start":"2026-04-02T08:57:03.831163Z","end":"2026-04-02T08:57:04.030102Z","steps":["trace[535605192] 'agreement among raft nodes before linearized reading' (duration: 198.724849ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:04.051541Z","caller":"traceutil/trace.go:171","msg":"trace[922102820] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"103.089088ms","start":"2026-04-02T08:57:03.948435Z","end":"2026-04-02T08:57:04.051524Z","steps":["trace[922102820] 'process raft request' (duration: 102.898904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:57:04.051837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.595985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-02T08:57:04.051887Z","caller":"traceutil/trace.go:171","msg":"trace[1268782733] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4751; }","duration":"110.702417ms","start":"2026-04-02T08:57:03.941173Z","end":"2026-04-02T08:57:04.051876Z","steps":["trace[1268782733] 'agreement among raft nodes before linearized reading' (duration: 110.554354ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:04.051901Z","caller":"traceutil/trace.go:171","msg":"trace[248151826] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4751; }","duration":"103.099378ms","start":"2026-04-02T08:57:03.948784Z","end":"2026-04-02T08:57:04.051883Z","steps":["trace[248151826] 'process raft request' (duration: 102.709349ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:05.940229Z","caller":"traceutil/trace.go:171","msg":"trace[894533642] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"141.866974ms","start":"2026-04-02T08:57:05.798335Z","end":"2026-04-02T08:57:05.940202Z","steps":["trace[894533642] 'process raft request' (duration: 141.573047ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:10.454352Z","caller":"traceutil/trace.go:171","msg":"trace[30149286] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"119.983027ms","start":"2026-04-02T08:57:10.334318Z","end":"2026-04-02T08:57:10.454301Z","steps":["trace[30149286] 'process raft request' (duration: 119.746251ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:10.484504Z","caller":"traceutil/trace.go:171","msg":"trace[5570808] linearizableReadLoop","detail":"{readStateIndex:5144; appliedIndex:5142; }","duration":"132.207556ms","start":"2026-04-02T08:57:10.352273Z","end":"2026-04-02T08:57:10.484481Z","steps":["trace[5570808] 'read index received' (duration: 101.917031ms)","trace[5570808] 'applied index is now lower than readState.Index' (duration: 30.289605ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:57:10.484593Z","caller":"traceutil/trace.go:171","msg":"trace[1479791979] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"137.894691ms","start":"2026-04-02T08:57:10.346661Z","end":"2026-04-02T08:57:10.484556Z","steps":["trace[1479791979] 'process raft request' (duration: 137.683466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:57:10.484688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.39128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-02T08:57:10.484717Z","caller":"traceutil/trace.go:171","msg":"trace[1461606197] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4961; }","duration":"132.450831ms","start":"2026-04-02T08:57:10.352255Z","end":"2026-04-02T08:57:10.484706Z","steps":["trace[1461606197] 'agreement among raft nodes before linearized reading' (duration: 132.313088ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:17.066255Z","caller":"traceutil/trace.go:171","msg":"trace[949313114] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"139.265713ms","start":"2026-04-02T08:57:16.926964Z","end":"2026-04-02T08:57:17.066229Z","steps":["trace[949313114] 'process raft request' (duration: 138.959856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:57:25.099761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.17962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b2f74b21-6157-4fa4-bad7-53e8490522e5\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-02T08:57:25.099818Z","caller":"traceutil/trace.go:171","msg":"trace[1086830522] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b2f74b21-6157-4fa4-bad7-53e8490522e5; range_end:; response_count:1; response_revision:5233; }","duration":"154.269202ms","start":"2026-04-02T08:57:24.945537Z","end":"2026-04-02T08:57:25.099806Z","steps":["trace[1086830522] 'range keys from in-memory index tree' (duration: 154.014565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:57:25.09975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.492692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T08:57:25.099929Z","caller":"traceutil/trace.go:171","msg":"trace[1355237427] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5233; }","duration":"166.706117ms","start":"2026-04-02T08:57:24.933193Z","end":"2026-04-02T08:57:25.099899Z","steps":["trace[1355237427] 'count revisions from in-memory index tree' (duration: 166.368019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:57:25.100106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.480232ms","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-04-02T08:57:25.100153Z","caller":"traceutil/trace.go:171","msg":"trace[2034424300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5233; }","duration":"148.564975ms","start":"2026-04-02T08:57:24.951577Z","end":"2026-04-02T08:57:25.100142Z","steps":["trace[2034424300] 'range keys from in-memory index tree' (duration: 148.182415ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:25.214771Z","caller":"traceutil/trace.go:171","msg":"trace[1927497327] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"115.9565ms","start":"2026-04-02T08:57:25.098792Z","end":"2026-04-02T08:57:25.214748Z","steps":["trace[1927497327] 'process raft request' (duration: 115.720583ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:25.237394Z","caller":"traceutil/trace.go:171","msg":"trace[584108949] linearizableReadLoop","detail":"{readStateIndex:5425; appliedIndex:5423; }","duration":"105.48654ms","start":"2026-04-02T08:57:25.131885Z","end":"2026-04-02T08:57:25.237372Z","steps":["trace[584108949] 'read index received' (duration: 82.677327ms)","trace[584108949] 'applied index is now lower than readState.Index' (duration: 22.807953ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:57:25.237456Z","caller":"traceutil/trace.go:171","msg":"trace[676089937] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"131.99859ms","start":"2026-04-02T08:57:25.105433Z","end":"2026-04-02T08:57:25.237431Z","steps":["trace[676089937] 'process raft request' (duration: 131.791476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:57:25.237564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.650614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:57:25.237599Z","caller":"traceutil/trace.go:171","msg":"trace[374194622] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5235; }","duration":"105.708525ms","start":"2026-04-02T08:57:25.131879Z","end":"2026-04-02T08:57:25.237588Z","steps":["trace[374194622] 'agreement among raft nodes before linearized reading' (duration: 105.589922ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:25.607747Z","caller":"traceutil/trace.go:171","msg":"trace[1825047573] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"135.023252ms","start":"2026-04-02T08:57:25.472696Z","end":"2026-04-02T08:57:25.607719Z","steps":["trace[1825047573] 'process raft request' (duration: 134.867098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:57:25.916517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.760469ms","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":"info","ts":"2026-04-02T08:57:25.916595Z","caller":"traceutil/trace.go:171","msg":"trace[1740433555] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5238; }","duration":"198.911933ms","start":"2026-04-02T08:57:25.717667Z","end":"2026-04-02T08:57:25.916579Z","steps":["trace[1740433555] 'count revisions from in-memory index tree' (duration: 198.668997ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:37.385441Z","caller":"traceutil/trace.go:171","msg":"trace[2111548450] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"130.70687ms","start":"2026-04-02T08:57:37.254691Z","end":"2026-04-02T08:57:37.385398Z","steps":["trace[2111548450] 'process raft request' (duration: 130.435084ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:37.516414Z","caller":"traceutil/trace.go:171","msg":"trace[2112091592] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"126.240694ms","start":"2026-04-02T08:57:37.390137Z","end":"2026-04-02T08:57:37.516377Z","steps":["trace[2112091592] 'process raft request' (duration: 76.683044ms)","trace[2112091592] 'compare' (duration: 49.247653ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:57:37.51646Z","caller":"traceutil/trace.go:171","msg":"trace[1561728298] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"126.233443ms","start":"2026-04-02T08:57:37.390197Z","end":"2026-04-02T08:57:37.51643Z","steps":["trace[1561728298] 'process raft request' (duration: 126.10075ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:58:57.798868Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2970} {"level":"info","ts":"2026-04-02T08:58:57.913139Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2970,"took":"113.080376ms","hash":4085155346,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":33632256,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-02T08:58:57.9132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4085155346,"revision":2970,"compact-revision":-1} {"level":"info","ts":"2026-04-02T08:59:27.021175Z","caller":"traceutil/trace.go:171","msg":"trace[683576509] linearizableReadLoop","detail":"{readStateIndex:6497; appliedIndex:6496; }","duration":"140.972356ms","start":"2026-04-02T08:59:26.880177Z","end":"2026-04-02T08:59:27.021149Z","steps":["trace[683576509] 'read index received' (duration: 140.793241ms)","trace[683576509] 'applied index is now lower than readState.Index' (duration: 178.124µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:59:27.021203Z","caller":"traceutil/trace.go:171","msg":"trace[1698398760] transaction","detail":"{read_only:false; response_revision:6276; number_of_response:1; }","duration":"144.04493ms","start":"2026-04-02T08:59:26.877133Z","end":"2026-04-02T08:59:27.021178Z","steps":["trace[1698398760] 'process raft request' (duration: 143.861325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:59:27.021372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.180521ms","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-04-02T08:59:27.021426Z","caller":"traceutil/trace.go:171","msg":"trace[1728731040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6276; }","duration":"141.277394ms","start":"2026-04-02T08:59:26.880131Z","end":"2026-04-02T08:59:27.021409Z","steps":["trace[1728731040] 'agreement among raft nodes before linearized reading' (duration: 141.11275ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:59:27.02238Z","caller":"traceutil/trace.go:171","msg":"trace[257673845] transaction","detail":"{read_only:false; response_revision:6277; number_of_response:1; }","duration":"135.824752ms","start":"2026-04-02T08:59:26.886541Z","end":"2026-04-02T08:59:27.022366Z","steps":["trace[257673845] 'process raft request' (duration: 135.73843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:00:22.337942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.393223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2b52502f-febb-42a4-9651-ab40be3778ca\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-02T09:00:22.338025Z","caller":"traceutil/trace.go:171","msg":"trace[861489910] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2b52502f-febb-42a4-9651-ab40be3778ca; range_end:; response_count:1; response_revision:6566; }","duration":"322.495395ms","start":"2026-04-02T09:00:22.015515Z","end":"2026-04-02T09:00:22.33801Z","steps":["trace[861489910] 'range keys from in-memory index tree' (duration: 322.28987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:00:22.338055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:00:22.015508Z","time spent":"322.541026ms","remote":"127.0.0.1:34038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1458,"request content":"key:\"/registry/persistentvolumes/pvc-2b52502f-febb-42a4-9651-ab40be3778ca\" "} {"level":"warn","ts":"2026-04-02T09:00:22.337983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.927493ms","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-04-02T09:00:22.337956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.115477ms","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-04-02T09:00:22.33816Z","caller":"traceutil/trace.go:171","msg":"trace[1288200553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6566; }","duration":"153.152068ms","start":"2026-04-02T09:00:22.184976Z","end":"2026-04-02T09:00:22.338128Z","steps":["trace[1288200553] 'range keys from in-memory index tree' (duration: 152.711688ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:00:22.338231Z","caller":"traceutil/trace.go:171","msg":"trace[1587846393] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:6566; }","duration":"153.413304ms","start":"2026-04-02T09:00:22.184777Z","end":"2026-04-02T09:00:22.338191Z","steps":["trace[1587846393] 'count revisions from in-memory index tree' (duration: 153.045006ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:03:38.292125Z","caller":"traceutil/trace.go:171","msg":"trace[340555855] transaction","detail":"{read_only:false; response_revision:8136; number_of_response:1; }","duration":"105.133995ms","start":"2026-04-02T09:03:38.186971Z","end":"2026-04-02T09:03:38.292105Z","steps":["trace[340555855] 'process raft request' (duration: 104.813798ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:03:38.306015Z","caller":"traceutil/trace.go:171","msg":"trace[917833459] transaction","detail":"{read_only:false; response_revision:8137; number_of_response:1; }","duration":"116.624529ms","start":"2026-04-02T09:03:38.189366Z","end":"2026-04-02T09:03:38.305991Z","steps":["trace[917833459] 'process raft request' (duration: 116.495416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:03:51.705499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.703596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T09:03:51.705499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.11392ms","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-04-02T09:03:51.705607Z","caller":"traceutil/trace.go:171","msg":"trace[1335131285] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8208; }","duration":"159.239863ms","start":"2026-04-02T09:03:51.546356Z","end":"2026-04-02T09:03:51.705596Z","steps":["trace[1335131285] 'range keys from in-memory index tree' (duration: 159.09934ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:03:51.705607Z","caller":"traceutil/trace.go:171","msg":"trace[2133516695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8208; }","duration":"182.861369ms","start":"2026-04-02T09:03:51.52272Z","end":"2026-04-02T09:03:51.705581Z","steps":["trace[2133516695] 'range keys from in-memory index tree' (duration: 182.613693ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:03:51.705708Z","caller":"traceutil/trace.go:171","msg":"trace[1449302580] transaction","detail":"{read_only:false; response_revision:8209; number_of_response:1; }","duration":"132.445871ms","start":"2026-04-02T09:03:51.573222Z","end":"2026-04-02T09:03:51.705668Z","steps":["trace[1449302580] 'process raft request' (duration: 131.529879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:03:51.705907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.967398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zwcq8\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-02T09:03:51.705965Z","caller":"traceutil/trace.go:171","msg":"trace[468791539] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zwcq8; range_end:; response_count:1; response_revision:8208; }","duration":"151.256444ms","start":"2026-04-02T09:03:51.554695Z","end":"2026-04-02T09:03:51.705951Z","steps":["trace[468791539] 'range keys from in-memory index tree' (duration: 150.556669ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:03:51.779375Z","caller":"traceutil/trace.go:171","msg":"trace[1398290795] transaction","detail":"{read_only:false; response_revision:8210; number_of_response:1; }","duration":"195.709913ms","start":"2026-04-02T09:03:51.583637Z","end":"2026-04-02T09:03:51.779346Z","steps":["trace[1398290795] 'process raft request' (duration: 195.506347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:03:53.412588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366736919111093,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T09:03:53.453231Z","caller":"traceutil/trace.go:171","msg":"trace[1352453771] linearizableReadLoop","detail":"{readStateIndex:8499; appliedIndex:8498; }","duration":"541.711595ms","start":"2026-04-02T09:03:52.911488Z","end":"2026-04-02T09:03:53.4532Z","steps":["trace[1352453771] 'read index received' (duration: 541.49079ms)","trace[1352453771] 'applied index is now lower than readState.Index' (duration: 219.775µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:03:53.45329Z","caller":"traceutil/trace.go:171","msg":"trace[1347133588] transaction","detail":"{read_only:false; response_revision:8215; number_of_response:1; }","duration":"618.013775ms","start":"2026-04-02T09:03:52.835238Z","end":"2026-04-02T09:03:53.453252Z","steps":["trace[1347133588] 'process raft request' (duration: 617.781519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:03:53.453441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:03:52.835226Z","time spent":"618.130757ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:03:53.453487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.971302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T09:03:53.453561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.631742ms","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-04-02T09:03:53.453629Z","caller":"traceutil/trace.go:171","msg":"trace[1104967960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8215; }","duration":"131.700674ms","start":"2026-04-02T09:03:53.321913Z","end":"2026-04-02T09:03:53.453614Z","steps":["trace[1104967960] 'agreement among raft nodes before linearized reading' (duration: 131.53471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:03:53.453675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.430501ms","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-04-02T09:03:53.453762Z","caller":"traceutil/trace.go:171","msg":"trace[1822185712] 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:8215; }","duration":"424.584255ms","start":"2026-04-02T09:03:53.029156Z","end":"2026-04-02T09:03:53.45374Z","steps":["trace[1822185712] 'agreement among raft nodes before linearized reading' (duration: 424.239107ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:03:53.453564Z","caller":"traceutil/trace.go:171","msg":"trace[302791725] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8215; }","duration":"542.112565ms","start":"2026-04-02T09:03:52.911429Z","end":"2026-04-02T09:03:53.453542Z","steps":["trace[302791725] 'agreement among raft nodes before linearized reading' (duration: 541.948262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:03:53.453847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:03:52.91141Z","time spent":"542.423322ms","remote":"127.0.0.1:40240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-02T09:03:53.453872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:03:53.029132Z","time spent":"424.696928ms","remote":"127.0.0.1:34046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-04-02T09:03:53.636261Z","caller":"traceutil/trace.go:171","msg":"trace[156409460] transaction","detail":"{read_only:false; response_revision:8217; number_of_response:1; }","duration":"177.82285ms","start":"2026-04-02T09:03:53.458409Z","end":"2026-04-02T09:03:53.636232Z","steps":["trace[156409460] 'process raft request' (duration: 98.387438ms)","trace[156409460] 'compare' (duration: 79.261588ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:03:53.636435Z","caller":"traceutil/trace.go:171","msg":"trace[641128762] linearizableReadLoop","detail":"{readStateIndex:8502; appliedIndex:8500; }","duration":"112.900021ms","start":"2026-04-02T09:03:53.523505Z","end":"2026-04-02T09:03:53.636405Z","steps":["trace[641128762] 'read index received' (duration: 33.218453ms)","trace[641128762] 'applied index is now lower than readState.Index' (duration: 79.679628ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:03:53.636505Z","caller":"traceutil/trace.go:171","msg":"trace[1110524280] transaction","detail":"{read_only:false; response_revision:8218; number_of_response:1; }","duration":"177.981184ms","start":"2026-04-02T09:03:53.458502Z","end":"2026-04-02T09:03:53.636483Z","steps":["trace[1110524280] 'process raft request' (duration: 177.676387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:03:53.636568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.045375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T09:03:53.636624Z","caller":"traceutil/trace.go:171","msg":"trace[618844095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8218; }","duration":"113.148977ms","start":"2026-04-02T09:03:53.523456Z","end":"2026-04-02T09:03:53.636605Z","steps":["trace[618844095] 'agreement among raft nodes before linearized reading' (duration: 113.052264ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:03:57.804562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6081} {"level":"info","ts":"2026-04-02T09:03:57.901707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6081,"took":"95.630563ms","hash":739571344,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":22654976,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-02T09:03:57.901801Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":739571344,"revision":6081,"compact-revision":2970} {"level":"info","ts":"2026-04-02T09:04:19.888319Z","caller":"traceutil/trace.go:171","msg":"trace[1711544398] linearizableReadLoop","detail":"{readStateIndex:8702; appliedIndex:8701; }","duration":"135.534325ms","start":"2026-04-02T09:04:19.752759Z","end":"2026-04-02T09:04:19.888294Z","steps":["trace[1711544398] 'read index received' (duration: 135.470773ms)","trace[1711544398] 'applied index is now lower than readState.Index' (duration: 59.772µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:04:19.888569Z","caller":"traceutil/trace.go:171","msg":"trace[449936158] transaction","detail":"{read_only:false; response_revision:8410; number_of_response:1; }","duration":"263.678355ms","start":"2026-04-02T09:04:19.624874Z","end":"2026-04-02T09:04:19.888553Z","steps":["trace[449936158] 'process raft request' (duration: 263.279345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:04:19.888846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.073137ms","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-04-02T09:04:19.888896Z","caller":"traceutil/trace.go:171","msg":"trace[1476353711] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8410; }","duration":"136.149768ms","start":"2026-04-02T09:04:19.75272Z","end":"2026-04-02T09:04:19.888869Z","steps":["trace[1476353711] 'agreement among raft nodes before linearized reading' (duration: 136.027815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:04:19.88977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.820954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-02T09:04:19.889806Z","caller":"traceutil/trace.go:171","msg":"trace[633081372] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8412; }","duration":"133.869695ms","start":"2026-04-02T09:04:19.755927Z","end":"2026-04-02T09:04:19.889797Z","steps":["trace[633081372] 'agreement among raft nodes before linearized reading' (duration: 133.780863ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:04:22.098858Z","caller":"traceutil/trace.go:171","msg":"trace[12505080] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"203.158628ms","start":"2026-04-02T09:04:21.895675Z","end":"2026-04-02T09:04:22.098833Z","steps":["trace[12505080] 'process raft request' (duration: 203.004865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:04:22.274704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.448754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T09:04:22.274929Z","caller":"traceutil/trace.go:171","msg":"trace[712224403] linearizableReadLoop","detail":"{readStateIndex:8715; appliedIndex:8714; }","duration":"375.101819ms","start":"2026-04-02T09:04:21.899811Z","end":"2026-04-02T09:04:22.274913Z","steps":["trace[712224403] 'read index received' (duration: 199.02247ms)","trace[712224403] 'applied index is now lower than readState.Index' (duration: 176.078389ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:04:22.274947Z","caller":"traceutil/trace.go:171","msg":"trace[1505234777] transaction","detail":"{read_only:false; response_revision:8423; number_of_response:1; }","duration":"368.673927ms","start":"2026-04-02T09:04:21.906254Z","end":"2026-04-02T09:04:22.274928Z","steps":["trace[1505234777] 'process raft request' (duration: 368.560695ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:04:22.275016Z","caller":"traceutil/trace.go:171","msg":"trace[1478432297] transaction","detail":"{read_only:false; response_revision:8424; number_of_response:1; }","duration":"286.342109ms","start":"2026-04-02T09:04:21.988665Z","end":"2026-04-02T09:04:22.275008Z","steps":["trace[1478432297] 'process raft request' (duration: 286.208815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:04:22.27503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:04:21.906243Z","time spent":"368.745011ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:04:22.274947Z","caller":"traceutil/trace.go:171","msg":"trace[911272873] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"379.066983ms","start":"2026-04-02T09:04:21.895836Z","end":"2026-04-02T09:04:22.274903Z","steps":["trace[911272873] 'process raft request' (duration: 259.341872ms)","trace[911272873] 'compare' (duration: 119.132697ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T09:04:22.275179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:04:21.895809Z","time spent":"379.29635ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:04:22.275207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.389257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-02T09:04:22.275237Z","caller":"traceutil/trace.go:171","msg":"trace[1778959911] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8424; }","duration":"375.448388ms","start":"2026-04-02T09:04:21.89978Z","end":"2026-04-02T09:04:22.275228Z","steps":["trace[1778959911] 'agreement among raft nodes before linearized reading' (duration: 375.331726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:04:22.275272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:04:21.899769Z","time spent":"375.495839ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-02T09:04:22.275402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.151838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zwcq8\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-04-02T09:04:22.275405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.575011ms","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-04-02T09:04:22.275433Z","caller":"traceutil/trace.go:171","msg":"trace[564961882] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zwcq8; range_end:; response_count:1; response_revision:8424; }","duration":"276.198809ms","start":"2026-04-02T09:04:21.999227Z","end":"2026-04-02T09:04:22.275426Z","steps":["trace[564961882] 'agreement among raft nodes before linearized reading' (duration: 276.117527ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:04:22.275443Z","caller":"traceutil/trace.go:171","msg":"trace[1433951557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8424; }","duration":"375.623772ms","start":"2026-04-02T09:04:21.899807Z","end":"2026-04-02T09:04:22.275431Z","steps":["trace[1433951557] 'agreement among raft nodes before linearized reading' (duration: 375.53561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:04:22.275471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:04:21.899799Z","time spent":"375.665213ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T09:04:22.695492Z","caller":"traceutil/trace.go:171","msg":"trace[310714893] transaction","detail":"{read_only:false; response_revision:8428; number_of_response:1; }","duration":"116.23748ms","start":"2026-04-02T09:04:22.579233Z","end":"2026-04-02T09:04:22.69547Z","steps":["trace[310714893] 'process raft request' (duration: 116.124678ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:04:32.961237Z","caller":"traceutil/trace.go:171","msg":"trace[906548863] linearizableReadLoop","detail":"{readStateIndex:8824; appliedIndex:8823; }","duration":"140.950491ms","start":"2026-04-02T09:04:32.820265Z","end":"2026-04-02T09:04:32.961216Z","steps":["trace[906548863] 'read index received' (duration: 140.758337ms)","trace[906548863] 'applied index is now lower than readState.Index' (duration: 191.324µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:04:32.961369Z","caller":"traceutil/trace.go:171","msg":"trace[1032927410] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"144.909825ms","start":"2026-04-02T09:04:32.816418Z","end":"2026-04-02T09:04:32.961328Z","steps":["trace[1032927410] 'process raft request' (duration: 144.663369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:04:32.961487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.177607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-d4ttw\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-04-02T09:04:32.96207Z","caller":"traceutil/trace.go:171","msg":"trace[812641573] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-d4ttw; range_end:; response_count:1; response_revision:8524; }","duration":"141.817832ms","start":"2026-04-02T09:04:32.820225Z","end":"2026-04-02T09:04:32.962043Z","steps":["trace[812641573] 'agreement among raft nodes before linearized reading' (duration: 141.106655ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:04:33.81036Z","caller":"traceutil/trace.go:171","msg":"trace[137229217] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"107.903333ms","start":"2026-04-02T09:04:33.702401Z","end":"2026-04-02T09:04:33.810304Z","steps":["trace[137229217] 'process raft request' (duration: 107.656147ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:04:35.102269Z","caller":"traceutil/trace.go:171","msg":"trace[1111471978] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"119.143857ms","start":"2026-04-02T09:04:34.983088Z","end":"2026-04-02T09:04:35.102232Z","steps":["trace[1111471978] 'process raft request' (duration: 49.236869ms)","trace[1111471978] 'compare' (duration: 69.687073ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:05:24.583882Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002492.wal"} {"level":"info","ts":"2026-04-02T09:05:47.459001Z","caller":"traceutil/trace.go:171","msg":"trace[1498859117] transaction","detail":"{read_only:false; response_revision:9202; number_of_response:1; }","duration":"105.714261ms","start":"2026-04-02T09:05:47.353266Z","end":"2026-04-02T09:05:47.45898Z","steps":["trace[1498859117] 'process raft request' (duration: 105.572407ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:06:37.637774Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-02T09:06:37.642897Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-02T09:06:37.642971Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-02T09:08:57.810899Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8247} {"level":"info","ts":"2026-04-02T09:08:57.885139Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8247,"took":"71.827339ms","hash":246468619,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":25092096,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-02T09:08:57.885199Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":246468619,"revision":8247,"compact-revision":6081} {"level":"info","ts":"2026-04-02T09:13:57.81711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10673} {"level":"info","ts":"2026-04-02T09:13:57.900946Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10673,"took":"82.071252ms","hash":70709634,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":20652032,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-02T09:13:57.901008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":70709634,"revision":10673,"compact-revision":8247}