{"level":"warn","ts":"2026-04-07T17:51:16.629384Z","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-07T17:51:16.62948Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.122: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.122:2380","--initial-cluster=instance=https://199.204.45.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.122: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-07T17:51:16.629553Z","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-07T17:51:16.629563Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-04-07T17:51:16.629579Z","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-07T17:51:16.630346Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"]} {"level":"info","ts":"2026-04-07T17:51:16.630425Z","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.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.122: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-07T17:51:16.634202Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.558487ms"} {"level":"info","ts":"2026-04-07T17:51:16.641432Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d2c3f297b56db0c4","cluster-id":"becafec23d27c587"} {"level":"info","ts":"2026-04-07T17:51:16.641545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T17:51:16.641602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 0"} {"level":"info","ts":"2026-04-07T17:51:16.641623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d2c3f297b56db0c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T17:51:16.641632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 1"} {"level":"info","ts":"2026-04-07T17:51:16.641702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"warn","ts":"2026-04-07T17:51:16.647918Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T17:51:16.650699Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T17:51:16.652387Z","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-07T17:51:16.656468Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d2c3f297b56db0c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T17:51:16.656736Z","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-07T17:51:16.656886Z","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-07T17:51:16.656903Z","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-07T17:51:16.657168Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d2c3f297b56db0c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T17:51:16.657916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"info","ts":"2026-04-07T17:51:16.658276Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","added-peer-id":"d2c3f297b56db0c4","added-peer-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-04-07T17:51:16.662977Z","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-07T17:51:16.663318Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-04-07T17:51:16.66339Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-04-07T17:51:16.663733Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d2c3f297b56db0c4","initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T17:51:16.663822Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T17:51:17.14236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T17:51:17.142486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T17:51:17.142521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgPreVoteResp from d2c3f297b56db0c4 at term 1"} {"level":"info","ts":"2026-04-07T17:51:17.142549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became candidate at term 2"} {"level":"info","ts":"2026-04-07T17:51:17.142646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgVoteResp from d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-04-07T17:51:17.142667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became leader at term 2"} {"level":"info","ts":"2026-04-07T17:51:17.142692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d2c3f297b56db0c4 elected leader d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-04-07T17:51:17.143898Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d2c3f297b56db0c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.122:2379]}","request-path":"/0/members/d2c3f297b56db0c4/attributes","cluster-id":"becafec23d27c587","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T17:51:17.14391Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:51:17.143998Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:51:17.144234Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:51:17.144426Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T17:51:17.144459Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T17:51:17.14497Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:51:17.145125Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:51:17.14519Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:51:17.145968Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T17:51:17.147518Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.122:2379"} {"level":"info","ts":"2026-04-07T17:51:54.905608Z","caller":"traceutil/trace.go:171","msg":"trace[994177615] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"229.251733ms","start":"2026-04-07T17:51:54.676325Z","end":"2026-04-07T17:51:54.905577Z","steps":["trace[994177615] 'process raft request' (duration: 229.006238ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:55.039749Z","caller":"traceutil/trace.go:171","msg":"trace[697536290] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"136.43171ms","start":"2026-04-07T17:51:54.903289Z","end":"2026-04-07T17:51:55.039721Z","steps":["trace[697536290] 'process raft request' (duration: 105.511942ms)","trace[697536290] 'compare' (duration: 30.52913ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:55.039747Z","caller":"traceutil/trace.go:171","msg":"trace[1399698799] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"134.173009ms","start":"2026-04-07T17:51:54.905544Z","end":"2026-04-07T17:51:55.039717Z","steps":["trace[1399698799] 'read index received' (duration: 103.080868ms)","trace[1399698799] 'applied index is now lower than readState.Index' (duration: 31.090861ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:55.039949Z","caller":"traceutil/trace.go:171","msg":"trace[2042038486] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"129.563556ms","start":"2026-04-07T17:51:54.910349Z","end":"2026-04-07T17:51:55.039912Z","steps":["trace[2042038486] 'process raft request' (duration: 129.338522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:55.040215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.556062ms","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-07T17:51:55.040429Z","caller":"traceutil/trace.go:171","msg":"trace[1003708590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"151.850927ms","start":"2026-04-07T17:51:54.88855Z","end":"2026-04-07T17:51:55.040401Z","steps":["trace[1003708590] 'agreement among raft nodes before linearized reading' (duration: 151.242586ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:55.186877Z","caller":"traceutil/trace.go:171","msg":"trace[1415300524] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"141.088304ms","start":"2026-04-07T17:51:55.045742Z","end":"2026-04-07T17:51:55.18683Z","steps":["trace[1415300524] 'process raft request' (duration: 117.767663ms)","trace[1415300524] 'compare' (duration: 23.049295ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:55.655946Z","caller":"traceutil/trace.go:171","msg":"trace[585774376] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"438.059157ms","start":"2026-04-07T17:51:55.217854Z","end":"2026-04-07T17:51:55.655913Z","steps":["trace[585774376] 'process raft request' (duration: 380.107532ms)","trace[585774376] 'compare' (duration: 57.748001ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:51:55.656173Z","caller":"traceutil/trace.go:171","msg":"trace[1495257124] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"379.012403ms","start":"2026-04-07T17:51:55.277132Z","end":"2026-04-07T17:51:55.656144Z","steps":["trace[1495257124] 'read index received' (duration: 320.816784ms)","trace[1495257124] 'applied index is now lower than readState.Index' (duration: 58.192699ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:51:55.657819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.681983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:51:55.658086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:55.217829Z","time spent":"438.207269ms","remote":"127.0.0.1:33850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:51:55.660454Z","caller":"traceutil/trace.go:171","msg":"trace[1534740236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"383.152777ms","start":"2026-04-07T17:51:55.277045Z","end":"2026-04-07T17:51:55.660198Z","steps":["trace[1534740236] 'agreement among raft nodes before linearized reading' (duration: 380.180304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:55.660756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:55.27699Z","time spent":"383.706237ms","remote":"127.0.0.1:33640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T17:53:11.77222Z","caller":"traceutil/trace.go:171","msg":"trace[415969241] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"143.474688ms","start":"2026-04-07T17:53:11.628718Z","end":"2026-04-07T17:53:11.772192Z","steps":["trace[415969241] 'process raft request' (duration: 68.935114ms)","trace[415969241] 'compare' (duration: 74.360981ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:35.501994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.668049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:35.502126Z","caller":"traceutil/trace.go:171","msg":"trace[1660257773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1885; }","duration":"226.859142ms","start":"2026-04-07T17:53:35.275246Z","end":"2026-04-07T17:53:35.502105Z","steps":["trace[1660257773] 'range keys from in-memory index tree' (duration: 226.565526ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:35.545339Z","caller":"traceutil/trace.go:171","msg":"trace[1133733652] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1959; }","duration":"178.542726ms","start":"2026-04-07T17:53:35.366776Z","end":"2026-04-07T17:53:35.545318Z","steps":["trace[1133733652] 'read index received' (duration: 168.649929ms)","trace[1133733652] 'applied index is now lower than readState.Index' (duration: 9.891177ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:35.545624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.860751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-07T17:53:35.5456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.7465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:35.545685Z","caller":"traceutil/trace.go:171","msg":"trace[1069148091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1885; }","duration":"145.966184ms","start":"2026-04-07T17:53:35.399704Z","end":"2026-04-07T17:53:35.545671Z","steps":["trace[1069148091] 'agreement among raft nodes before linearized reading' (duration: 145.76576ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:35.545754Z","caller":"traceutil/trace.go:171","msg":"trace[1378936364] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1885; }","duration":"178.945684ms","start":"2026-04-07T17:53:35.366748Z","end":"2026-04-07T17:53:35.545694Z","steps":["trace[1378936364] 'agreement among raft nodes before linearized reading' (duration: 178.69495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:35.545836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.565156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T17:53:35.545912Z","caller":"traceutil/trace.go:171","msg":"trace[673992768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1885; }","duration":"145.687768ms","start":"2026-04-07T17:53:35.400206Z","end":"2026-04-07T17:53:35.545894Z","steps":["trace[673992768] 'agreement among raft nodes before linearized reading' (duration: 145.433713ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:35.711338Z","caller":"traceutil/trace.go:171","msg":"trace[815938650] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"115.283643ms","start":"2026-04-07T17:53:35.596015Z","end":"2026-04-07T17:53:35.711299Z","steps":["trace[815938650] 'process raft request' (duration: 36.006083ms)","trace[815938650] 'compare' (duration: 79.126048ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:35.983849Z","caller":"traceutil/trace.go:171","msg":"trace[1234103327] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"238.755335ms","start":"2026-04-07T17:53:35.745057Z","end":"2026-04-07T17:53:35.983812Z","steps":["trace[1234103327] 'process raft request' (duration: 179.602406ms)","trace[1234103327] 'compare' (duration: 58.844643ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:35.984353Z","caller":"traceutil/trace.go:171","msg":"trace[479000171] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1963; }","duration":"114.932957ms","start":"2026-04-07T17:53:35.869398Z","end":"2026-04-07T17:53:35.984331Z","steps":["trace[479000171] 'read index received' (duration: 55.248508ms)","trace[479000171] 'applied index is now lower than readState.Index' (duration: 59.683489ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:35.984357Z","caller":"traceutil/trace.go:171","msg":"trace[331024556] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"236.671797ms","start":"2026-04-07T17:53:35.747653Z","end":"2026-04-07T17:53:35.984325Z","steps":["trace[331024556] 'process raft request' (duration: 236.074527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:35.984582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.172081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f815919e-50d4-4f28-9040-aa542185133d\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-04-07T17:53:35.98465Z","caller":"traceutil/trace.go:171","msg":"trace[14889156] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f815919e-50d4-4f28-9040-aa542185133d; range_end:; response_count:1; response_revision:1891; }","duration":"115.301764ms","start":"2026-04-07T17:53:35.86933Z","end":"2026-04-07T17:53:35.984632Z","steps":["trace[14889156] 'agreement among raft nodes before linearized reading' (duration: 115.175421ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:35.984453Z","caller":"traceutil/trace.go:171","msg":"trace[1362133517] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"123.128994ms","start":"2026-04-07T17:53:35.861312Z","end":"2026-04-07T17:53:35.984441Z","steps":["trace[1362133517] 'process raft request' (duration: 122.87835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:26.418192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.323863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T17:54:26.418247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.231225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:54:26.418315Z","caller":"traceutil/trace.go:171","msg":"trace[725828086] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2211; }","duration":"123.486486ms","start":"2026-04-07T17:54:26.29481Z","end":"2026-04-07T17:54:26.418296Z","steps":["trace[725828086] 'count revisions from in-memory index tree' (duration: 123.221331ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:54:26.418322Z","caller":"traceutil/trace.go:171","msg":"trace[366166389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2211; }","duration":"142.360547ms","start":"2026-04-07T17:54:26.275946Z","end":"2026-04-07T17:54:26.418307Z","steps":["trace[366166389] 'range keys from in-memory index tree' (duration: 142.022552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:54:26.418514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.447567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T17:54:26.418633Z","caller":"traceutil/trace.go:171","msg":"trace[742625169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2211; }","duration":"171.64519ms","start":"2026-04-07T17:54:26.246962Z","end":"2026-04-07T17:54:26.418607Z","steps":["trace[742625169] 'range keys from in-memory index tree' (duration: 171.155172ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:55:18.321637Z","caller":"traceutil/trace.go:171","msg":"trace[1908245049] transaction","detail":"{read_only:false; response_revision:2664; number_of_response:1; }","duration":"182.693967ms","start":"2026-04-07T17:55:18.138912Z","end":"2026-04-07T17:55:18.321606Z","steps":["trace[1908245049] 'process raft request' (duration: 182.500153ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:55:18.425721Z","caller":"traceutil/trace.go:171","msg":"trace[9186706] linearizableReadLoop","detail":"{readStateIndex:2766; appliedIndex:2764; }","duration":"205.908472ms","start":"2026-04-07T17:55:18.219781Z","end":"2026-04-07T17:55:18.425689Z","steps":["trace[9186706] 'read index received' (duration: 101.704853ms)","trace[9186706] 'applied index is now lower than readState.Index' (duration: 104.202489ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:55:18.425813Z","caller":"traceutil/trace.go:171","msg":"trace[1955451587] transaction","detail":"{read_only:false; response_revision:2665; number_of_response:1; }","duration":"279.398896ms","start":"2026-04-07T17:55:18.146384Z","end":"2026-04-07T17:55:18.425783Z","steps":["trace[1955451587] 'process raft request' (duration: 279.061779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:55:18.426095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.252878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T17:55:18.426232Z","caller":"traceutil/trace.go:171","msg":"trace[1312403514] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2665; }","duration":"206.472792ms","start":"2026-04-07T17:55:18.219737Z","end":"2026-04-07T17:55:18.42621Z","steps":["trace[1312403514] 'agreement among raft nodes before linearized reading' (duration: 206.226138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:55:18.42719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.309193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T17:55:18.427319Z","caller":"traceutil/trace.go:171","msg":"trace[1253450248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2666; }","duration":"172.510668ms","start":"2026-04-07T17:55:18.254779Z","end":"2026-04-07T17:55:18.42729Z","steps":["trace[1253450248] 'agreement among raft nodes before linearized reading' (duration: 172.13078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:55:18.427301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.837042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:55:18.427409Z","caller":"traceutil/trace.go:171","msg":"trace[1902987876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2666; }","duration":"150.995334ms","start":"2026-04-07T17:55:18.276393Z","end":"2026-04-07T17:55:18.427389Z","steps":["trace[1902987876] 'agreement among raft nodes before linearized reading' (duration: 150.837781ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:55:57.49012Z","caller":"traceutil/trace.go:171","msg":"trace[1804359332] transaction","detail":"{read_only:false; response_revision:2901; number_of_response:1; }","duration":"145.474011ms","start":"2026-04-07T17:55:57.344612Z","end":"2026-04-07T17:55:57.490086Z","steps":["trace[1804359332] 'process raft request' (duration: 145.232377ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:58:33.681359Z","caller":"traceutil/trace.go:171","msg":"trace[829103726] transaction","detail":"{read_only:false; response_revision:3925; number_of_response:1; }","duration":"180.814362ms","start":"2026-04-07T17:58:33.500512Z","end":"2026-04-07T17:58:33.681326Z","steps":["trace[829103726] 'process raft request' (duration: 128.884461ms)","trace[829103726] 'compare' (duration: 51.745566ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:58:33.681712Z","caller":"traceutil/trace.go:171","msg":"trace[793947976] transaction","detail":"{read_only:false; response_revision:3926; number_of_response:1; }","duration":"180.941143ms","start":"2026-04-07T17:58:33.500747Z","end":"2026-04-07T17:58:33.681689Z","steps":["trace[793947976] 'process raft request' (duration: 180.541476ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:00.019013Z","caller":"traceutil/trace.go:171","msg":"trace[1978338969] linearizableReadLoop","detail":"{readStateIndex:5308; appliedIndex:5307; }","duration":"146.784712ms","start":"2026-04-07T17:59:59.872199Z","end":"2026-04-07T18:00:00.018984Z","steps":["trace[1978338969] 'read index received' (duration: 146.377373ms)","trace[1978338969] 'applied index is now lower than readState.Index' (duration: 406.559µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:00:00.019147Z","caller":"traceutil/trace.go:171","msg":"trace[809606564] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"160.588848ms","start":"2026-04-07T17:59:59.858494Z","end":"2026-04-07T18:00:00.019083Z","steps":["trace[809606564] 'process raft request' (duration: 160.118609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:00.019298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.083227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-de42784b-7966-4469-8de0-87e47de2fa04\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-07T18:00:00.019368Z","caller":"traceutil/trace.go:171","msg":"trace[1425133996] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-de42784b-7966-4469-8de0-87e47de2fa04; range_end:; response_count:1; response_revision:5117; }","duration":"147.188039ms","start":"2026-04-07T17:59:59.872164Z","end":"2026-04-07T18:00:00.019352Z","steps":["trace[1425133996] 'agreement among raft nodes before linearized reading' (duration: 146.959305ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:00.02983Z","caller":"traceutil/trace.go:171","msg":"trace[1966574880] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"124.270154ms","start":"2026-04-07T17:59:59.905544Z","end":"2026-04-07T18:00:00.029814Z","steps":["trace[1966574880] 'process raft request' (duration: 124.135721ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:00.030196Z","caller":"traceutil/trace.go:171","msg":"trace[2031372498] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"118.126571ms","start":"2026-04-07T17:59:59.912059Z","end":"2026-04-07T18:00:00.030185Z","steps":["trace[2031372498] 'process raft request' (duration: 117.916147ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:00.030225Z","caller":"traceutil/trace.go:171","msg":"trace[837905234] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"118.443227ms","start":"2026-04-07T17:59:59.911763Z","end":"2026-04-07T18:00:00.030207Z","steps":["trace[837905234] 'process raft request' (duration: 118.021848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:00.466726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.360435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T18:00:00.466831Z","caller":"traceutil/trace.go:171","msg":"trace[807699220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5123; }","duration":"193.523678ms","start":"2026-04-07T18:00:00.273287Z","end":"2026-04-07T18:00:00.466811Z","steps":["trace[807699220] 'range keys from in-memory index tree' (duration: 193.249133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:00.466896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.613811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T18:00:00.467026Z","caller":"traceutil/trace.go:171","msg":"trace[379497335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5123; }","duration":"267.817026ms","start":"2026-04-07T18:00:00.199176Z","end":"2026-04-07T18:00:00.466993Z","steps":["trace[379497335] 'range keys from in-memory index tree' (duration: 267.273525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:00.467179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.070003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-07T18:00:00.467191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.066711ms","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-07T18:00:00.467236Z","caller":"traceutil/trace.go:171","msg":"trace[888729902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5123; }","duration":"266.547843ms","start":"2026-04-07T18:00:00.200667Z","end":"2026-04-07T18:00:00.467214Z","steps":["trace[888729902] 'range keys from in-memory index tree' (duration: 265.88674ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:00.467295Z","caller":"traceutil/trace.go:171","msg":"trace[130795243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5123; }","duration":"268.216073ms","start":"2026-04-07T18:00:00.199052Z","end":"2026-04-07T18:00:00.467268Z","steps":["trace[130795243] 'range keys from in-memory index tree' (duration: 267.861307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:00.467356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.211011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T18:00:00.46769Z","caller":"traceutil/trace.go:171","msg":"trace[593765524] linearizableReadLoop","detail":"{readStateIndex:5315; appliedIndex:5314; }","duration":"169.214118ms","start":"2026-04-07T18:00:00.298429Z","end":"2026-04-07T18:00:00.467644Z","steps":["trace[593765524] 'read index received' (duration: 32.291µs)","trace[593765524] 'applied index is now lower than readState.Index' (duration: 169.179937ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:00:00.467761Z","caller":"traceutil/trace.go:171","msg":"trace[1868573474] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"183.104425ms","start":"2026-04-07T18:00:00.284596Z","end":"2026-04-07T18:00:00.4677Z","steps":["trace[1868573474] 'compare' (duration: 173.970456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:00.468273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.450047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777673"} {"level":"info","ts":"2026-04-07T18:00:00.468318Z","caller":"traceutil/trace.go:171","msg":"trace[1940440645] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5124; }","duration":"124.54787ms","start":"2026-04-07T18:00:00.343759Z","end":"2026-04-07T18:00:00.468307Z","steps":["trace[1940440645] 'agreement among raft nodes before linearized reading' (duration: 124.038021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:00.46853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.094404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T18:00:00.468582Z","caller":"traceutil/trace.go:171","msg":"trace[837528564] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:5124; }","duration":"170.178216ms","start":"2026-04-07T18:00:00.298389Z","end":"2026-04-07T18:00:00.468567Z","steps":["trace[837528564] 'agreement among raft nodes before linearized reading' (duration: 170.045194ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:19.402717Z","caller":"traceutil/trace.go:171","msg":"trace[145734240] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"271.591886ms","start":"2026-04-07T18:00:19.131078Z","end":"2026-04-07T18:00:19.40267Z","steps":["trace[145734240] 'process raft request' (duration: 208.737553ms)","trace[145734240] 'compare' (duration: 62.341243ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:00:19.422738Z","caller":"traceutil/trace.go:171","msg":"trace[1676578293] transaction","detail":"{read_only:false; response_revision:5454; number_of_response:1; }","duration":"281.825757ms","start":"2026-04-07T18:00:19.140881Z","end":"2026-04-07T18:00:19.422706Z","steps":["trace[1676578293] 'process raft request' (duration: 281.607754ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:19.422974Z","caller":"traceutil/trace.go:171","msg":"trace[1575521321] linearizableReadLoop","detail":"{readStateIndex:5653; appliedIndex:5651; }","duration":"147.811097ms","start":"2026-04-07T18:00:19.275127Z","end":"2026-04-07T18:00:19.422938Z","steps":["trace[1575521321] 'read index received' (duration: 64.717397ms)","trace[1575521321] 'applied index is now lower than readState.Index' (duration: 83.09065ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T18:00:19.423152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.00616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T18:00:19.423194Z","caller":"traceutil/trace.go:171","msg":"trace[1767579614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5455; }","duration":"148.118972ms","start":"2026-04-07T18:00:19.275062Z","end":"2026-04-07T18:00:19.423181Z","steps":["trace[1767579614] 'agreement among raft nodes before linearized reading' (duration: 147.99591ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:19.423091Z","caller":"traceutil/trace.go:171","msg":"trace[1494825823] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"119.254425ms","start":"2026-04-07T18:00:19.303753Z","end":"2026-04-07T18:00:19.423007Z","steps":["trace[1494825823] 'process raft request' (duration: 118.888058ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:37.530935Z","caller":"traceutil/trace.go:171","msg":"trace[1240523414] transaction","detail":"{read_only:false; response_revision:5691; number_of_response:1; }","duration":"104.161622ms","start":"2026-04-07T18:00:37.426743Z","end":"2026-04-07T18:00:37.530904Z","steps":["trace[1240523414] 'process raft request' (duration: 16.6195ms)","trace[1240523414] 'compare' (duration: 86.891691ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:00:37.530955Z","caller":"traceutil/trace.go:171","msg":"trace[990557110] transaction","detail":"{read_only:false; response_revision:5692; number_of_response:1; }","duration":"102.92118ms","start":"2026-04-07T18:00:37.428011Z","end":"2026-04-07T18:00:37.530932Z","steps":["trace[990557110] 'process raft request' (duration: 102.614394ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:37.530977Z","caller":"traceutil/trace.go:171","msg":"trace[949728229] transaction","detail":"{read_only:false; response_revision:5693; number_of_response:1; }","duration":"102.804817ms","start":"2026-04-07T18:00:37.428152Z","end":"2026-04-07T18:00:37.530957Z","steps":["trace[949728229] 'process raft request' (duration: 102.564733ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:01:17.176608Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3012} {"level":"info","ts":"2026-04-07T18:01:17.257427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3012,"took":"78.923113ms","hash":2731608626,"current-db-size-bytes":57393152,"current-db-size":"57 MB","current-db-size-in-use-bytes":30949376,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T18:01:17.257509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2731608626,"revision":3012,"compact-revision":-1} {"level":"info","ts":"2026-04-07T18:01:20.443382Z","caller":"traceutil/trace.go:171","msg":"trace[1505330225] linearizableReadLoop","detail":"{readStateIndex:6270; appliedIndex:6269; }","duration":"106.445145ms","start":"2026-04-07T18:01:20.336869Z","end":"2026-04-07T18:01:20.443314Z","steps":["trace[1505330225] 'read index received' (duration: 89.594641ms)","trace[1505330225] 'applied index is now lower than readState.Index' (duration: 16.849264ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:01:20.443391Z","caller":"traceutil/trace.go:171","msg":"trace[894864066] transaction","detail":"{read_only:false; response_revision:6051; number_of_response:1; }","duration":"108.849001ms","start":"2026-04-07T18:01:20.334512Z","end":"2026-04-07T18:01:20.443361Z","steps":["trace[894864066] 'process raft request' (duration: 91.934186ms)","trace[894864066] 'compare' (duration: 16.720632ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:01:20.443671Z","caller":"traceutil/trace.go:171","msg":"trace[846057507] transaction","detail":"{read_only:false; response_revision:6052; number_of_response:1; }","duration":"102.343479ms","start":"2026-04-07T18:01:20.341311Z","end":"2026-04-07T18:01:20.443654Z","steps":["trace[846057507] 'process raft request' (duration: 101.962432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:01:20.443962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.054107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-07T18:01:20.444222Z","caller":"traceutil/trace.go:171","msg":"trace[599981604] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:6053; }","duration":"107.359224ms","start":"2026-04-07T18:01:20.336811Z","end":"2026-04-07T18:01:20.444171Z","steps":["trace[599981604] 'agreement among raft nodes before linearized reading' (duration: 106.766012ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:06:17.196284Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6035} {"level":"info","ts":"2026-04-07T18:06:17.293279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6035,"took":"94.801978ms","hash":1352497988,"current-db-size-bytes":57393152,"current-db-size":"57 MB","current-db-size-in-use-bytes":21585920,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-07T18:06:17.293356Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1352497988,"revision":6035,"compact-revision":3012} {"level":"info","ts":"2026-04-07T18:07:03.824665Z","caller":"traceutil/trace.go:171","msg":"trace[617311370] transaction","detail":"{read_only:false; response_revision:8534; number_of_response:1; }","duration":"131.754173ms","start":"2026-04-07T18:07:03.69288Z","end":"2026-04-07T18:07:03.824634Z","steps":["trace[617311370] 'process raft request' (duration: 131.53242ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:06.040439Z","caller":"traceutil/trace.go:171","msg":"trace[1455259341] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"155.596083ms","start":"2026-04-07T18:07:05.884813Z","end":"2026-04-07T18:07:06.040409Z","steps":["trace[1455259341] 'process raft request' (duration: 145.722645ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:06.084997Z","caller":"traceutil/trace.go:171","msg":"trace[547078823] linearizableReadLoop","detail":"{readStateIndex:8848; appliedIndex:8846; }","duration":"124.508923ms","start":"2026-04-07T18:07:05.960458Z","end":"2026-04-07T18:07:06.084967Z","steps":["trace[547078823] 'read index received' (duration: 70.091713ms)","trace[547078823] 'applied index is now lower than readState.Index' (duration: 54.41635ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:07:06.085115Z","caller":"traceutil/trace.go:171","msg":"trace[1073330539] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"200.104105ms","start":"2026-04-07T18:07:05.884953Z","end":"2026-04-07T18:07:06.085057Z","steps":["trace[1073330539] 'process raft request' (duration: 199.85683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:07:06.085242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.762268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T18:07:06.085307Z","caller":"traceutil/trace.go:171","msg":"trace[1988727035] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:8546; }","duration":"124.8948ms","start":"2026-04-07T18:07:05.960397Z","end":"2026-04-07T18:07:06.085292Z","steps":["trace[1988727035] 'agreement among raft nodes before linearized reading' (duration: 124.715537ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:34.351596Z","caller":"traceutil/trace.go:171","msg":"trace[1601622215] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"161.603032ms","start":"2026-04-07T18:07:34.189965Z","end":"2026-04-07T18:07:34.351568Z","steps":["trace[1601622215] 'process raft request' (duration: 160.91608ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:38.164558Z","caller":"traceutil/trace.go:171","msg":"trace[1131052488] linearizableReadLoop","detail":"{readStateIndex:9080; appliedIndex:9079; }","duration":"125.549421ms","start":"2026-04-07T18:07:38.038976Z","end":"2026-04-07T18:07:38.164526Z","steps":["trace[1131052488] 'read index received' (duration: 125.371928ms)","trace[1131052488] 'applied index is now lower than readState.Index' (duration: 176.483µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:07:38.164699Z","caller":"traceutil/trace.go:171","msg":"trace[1309280782] transaction","detail":"{read_only:false; response_revision:8769; number_of_response:1; }","duration":"181.669043ms","start":"2026-04-07T18:07:37.983019Z","end":"2026-04-07T18:07:38.164688Z","steps":["trace[1309280782] 'process raft request' (duration: 181.281026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:07:38.164979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.980959ms","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-07T18:07:38.165048Z","caller":"traceutil/trace.go:171","msg":"trace[461620581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8769; }","duration":"126.069151ms","start":"2026-04-07T18:07:38.038934Z","end":"2026-04-07T18:07:38.165003Z","steps":["trace[461620581] 'agreement among raft nodes before linearized reading' (duration: 125.913929ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:38.312739Z","caller":"traceutil/trace.go:171","msg":"trace[2074764106] transaction","detail":"{read_only:false; response_revision:8771; number_of_response:1; }","duration":"145.050562ms","start":"2026-04-07T18:07:38.167632Z","end":"2026-04-07T18:07:38.312683Z","steps":["trace[2074764106] 'process raft request' (duration: 46.576869ms)","trace[2074764106] 'compare' (duration: 98.27159ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:07:38.312743Z","caller":"traceutil/trace.go:171","msg":"trace[1085549315] transaction","detail":"{read_only:false; response_revision:8772; number_of_response:1; }","duration":"142.806153ms","start":"2026-04-07T18:07:38.169918Z","end":"2026-04-07T18:07:38.312724Z","steps":["trace[1085549315] 'process raft request' (duration: 142.710091ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:38.313076Z","caller":"traceutil/trace.go:171","msg":"trace[1940092815] linearizableReadLoop","detail":"{readStateIndex:9084; appliedIndex:9081; }","duration":"110.342538ms","start":"2026-04-07T18:07:38.202702Z","end":"2026-04-07T18:07:38.313045Z","steps":["trace[1940092815] 'read index received' (duration: 11.390866ms)","trace[1940092815] 'applied index is now lower than readState.Index' (duration: 98.949862ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:07:38.313228Z","caller":"traceutil/trace.go:171","msg":"trace[867623828] transaction","detail":"{read_only:false; response_revision:8773; number_of_response:1; }","duration":"142.04174ms","start":"2026-04-07T18:07:38.171175Z","end":"2026-04-07T18:07:38.313216Z","steps":["trace[867623828] 'process raft request' (duration: 141.500949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:07:38.313401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.688114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-87da2ef3-3eee-458c-9ff2-02f0e6902833\" ","response":"range_response_count:1 size:1293"} {"level":"info","ts":"2026-04-07T18:07:38.313472Z","caller":"traceutil/trace.go:171","msg":"trace[553309623] range","detail":"{range_begin:/registry/persistentvolumes/pvc-87da2ef3-3eee-458c-9ff2-02f0e6902833; range_end:; response_count:1; response_revision:8773; }","duration":"110.804586ms","start":"2026-04-07T18:07:38.202651Z","end":"2026-04-07T18:07:38.313456Z","steps":["trace[553309623] 'agreement among raft nodes before linearized reading' (duration: 110.574582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:07:48.796619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.496515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-07T18:07:48.796756Z","caller":"traceutil/trace.go:171","msg":"trace[767328847] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8874; }","duration":"205.047504ms","start":"2026-04-07T18:07:48.591683Z","end":"2026-04-07T18:07:48.796731Z","steps":["trace[767328847] 'range keys from in-memory index tree' (duration: 204.28291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:07:48.796783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.349653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-config\" ","response":"range_response_count:1 size:598"} {"level":"info","ts":"2026-04-07T18:07:48.796888Z","caller":"traceutil/trace.go:171","msg":"trace[1308887835] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-config; range_end:; response_count:1; response_revision:8874; }","duration":"203.492246ms","start":"2026-04-07T18:07:48.593371Z","end":"2026-04-07T18:07:48.796863Z","steps":["trace[1308887835] 'range keys from in-memory index tree' (duration: 203.1288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:07:48.797331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.398483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-07T18:07:48.797441Z","caller":"traceutil/trace.go:171","msg":"trace[1455728106] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8874; }","duration":"204.653218ms","start":"2026-04-07T18:07:48.592763Z","end":"2026-04-07T18:07:48.797417Z","steps":["trace[1455728106] 'range keys from in-memory index tree' (duration: 203.772521ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:48.866258Z","caller":"traceutil/trace.go:171","msg":"trace[1600944234] linearizableReadLoop","detail":"{readStateIndex:9192; appliedIndex:9191; }","duration":"118.127819ms","start":"2026-04-07T18:07:48.748106Z","end":"2026-04-07T18:07:48.866234Z","steps":["trace[1600944234] 'read index received' (duration: 117.910675ms)","trace[1600944234] 'applied index is now lower than readState.Index' (duration: 216.624µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:07:48.866556Z","caller":"traceutil/trace.go:171","msg":"trace[19453447] transaction","detail":"{read_only:false; response_revision:8875; number_of_response:1; }","duration":"266.488121ms","start":"2026-04-07T18:07:48.600059Z","end":"2026-04-07T18:07:48.866548Z","steps":["trace[19453447] 'process raft request' (duration: 265.982512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:07:48.867217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.090175ms","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-07T18:07:48.867273Z","caller":"traceutil/trace.go:171","msg":"trace[2001967901] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8875; }","duration":"119.214167ms","start":"2026-04-07T18:07:48.748045Z","end":"2026-04-07T18:07:48.86726Z","steps":["trace[2001967901] 'agreement among raft nodes before linearized reading' (duration: 118.656868ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:08:09.722094Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024da.wal"} {"level":"info","ts":"2026-04-07T18:09:12.199016Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d2c3f297b56db0c4","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T18:09:12.203877Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T18:09:12.203983Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T18:11:17.208638Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8112} {"level":"info","ts":"2026-04-07T18:11:17.275407Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8112,"took":"63.02514ms","hash":4238293165,"current-db-size-bytes":57393152,"current-db-size":"57 MB","current-db-size-in-use-bytes":27947008,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-07T18:11:17.275557Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4238293165,"revision":8112,"compact-revision":6035} {"level":"info","ts":"2026-04-07T18:16:17.215679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10523} {"level":"info","ts":"2026-04-07T18:16:17.300618Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10523,"took":"81.966422ms","hash":4216640284,"current-db-size-bytes":57393152,"current-db-size":"57 MB","current-db-size-in-use-bytes":22351872,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-07T18:16:17.300711Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4216640284,"revision":10523,"compact-revision":8112}