{"level":"warn","ts":"2026-04-07T01:24:48.800186Z","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-07T01:24:48.800274Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-07T01:24:48.80034Z","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-07T01:24:48.800349Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-07T01:24:48.800367Z","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-07T01:24:48.801037Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-04-07T01:24:48.801107Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-07T01:24:48.805097Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.740887ms"} {"level":"info","ts":"2026-04-07T01:24:48.8152Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-04-07T01:24:48.81532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T01:24:48.815361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-04-07T01:24:48.815376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T01:24:48.815394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-04-07T01:24:48.81545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-04-07T01:24:48.820987Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T01:24:48.822493Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T01:24:48.824077Z","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-07T01:24:48.826025Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T01:24:48.82614Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T01:24:48.826306Z","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-07T01:24:48.826351Z","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-07T01:24:48.826363Z","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-07T01:24:48.827112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-04-07T01:24:48.827195Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-07T01:24:48.830018Z","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-07T01:24:48.830242Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-07T01:24:48.830304Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-07T01:24:48.830526Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T01:24:48.830538Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T01:24:49.616252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T01:24:49.616312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T01:24:49.616381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-04-07T01:24:49.616408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-04-07T01:24:49.616425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-07T01:24:49.616446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-04-07T01:24:49.616464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-07T01:24:49.626195Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:49.627808Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T01:24:49.627947Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:24:49.628045Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:24:49.629823Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T01:24:49.632469Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-04-07T01:24:49.632387Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T01:24:49.632588Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T01:24:49.632686Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:49.63284Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:49.63289Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-07T01:24:59.885656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.217441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:24:59.885714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.567118ms","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-07T01:24:59.88577Z","caller":"traceutil/trace.go:171","msg":"trace[105185032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:390; }","duration":"231.657329ms","start":"2026-04-07T01:24:59.6541Z","end":"2026-04-07T01:24:59.885758Z","steps":["trace[105185032] 'range keys from in-memory index tree' (duration: 231.488305ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:59.885768Z","caller":"traceutil/trace.go:171","msg":"trace[1797081666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:390; }","duration":"114.405525ms","start":"2026-04-07T01:24:59.771349Z","end":"2026-04-07T01:24:59.885755Z","steps":["trace[1797081666] 'range keys from in-memory index tree' (duration: 114.144809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:59.885766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.859116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-07T01:24:59.885859Z","caller":"traceutil/trace.go:171","msg":"trace[915488359] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:390; }","duration":"368.021299ms","start":"2026-04-07T01:24:59.517819Z","end":"2026-04-07T01:24:59.88584Z","steps":["trace[915488359] 'range keys from in-memory index tree' (duration: 367.691542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:24:59.885912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:24:59.517798Z","time spent":"368.105282ms","remote":"127.0.0.1:58094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-07T01:25:00.107887Z","caller":"traceutil/trace.go:171","msg":"trace[636404571] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"214.963012ms","start":"2026-04-07T01:24:59.892907Z","end":"2026-04-07T01:25:00.10787Z","steps":["trace[636404571] 'process raft request' (duration: 214.85648ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:00.114037Z","caller":"traceutil/trace.go:171","msg":"trace[1091875117] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"152.119087ms","start":"2026-04-07T01:24:59.961888Z","end":"2026-04-07T01:25:00.114008Z","steps":["trace[1091875117] 'process raft request' (duration: 152.012415ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:25.877371Z","caller":"traceutil/trace.go:171","msg":"trace[8343240] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"103.65058ms","start":"2026-04-07T01:25:25.773689Z","end":"2026-04-07T01:25:25.87734Z","steps":["trace[8343240] 'read index received' (duration: 102.74619ms)","trace[8343240] 'applied index is now lower than readState.Index' (duration: 903.3µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:25:25.877563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.850904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:25:25.877599Z","caller":"traceutil/trace.go:171","msg":"trace[648332749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"103.906695ms","start":"2026-04-07T01:25:25.77368Z","end":"2026-04-07T01:25:25.877587Z","steps":["trace[648332749] 'agreement among raft nodes before linearized reading' (duration: 103.771252ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:25.877665Z","caller":"traceutil/trace.go:171","msg":"trace[1788960805] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"157.648125ms","start":"2026-04-07T01:25:25.71996Z","end":"2026-04-07T01:25:25.877608Z","steps":["trace[1788960805] 'process raft request' (duration: 156.694426ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:25.901884Z","caller":"traceutil/trace.go:171","msg":"trace[1311618772] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"107.899448ms","start":"2026-04-07T01:25:25.793947Z","end":"2026-04-07T01:25:25.901847Z","steps":["trace[1311618772] 'process raft request' (duration: 107.747155ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:26.078308Z","caller":"traceutil/trace.go:171","msg":"trace[1302887980] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"166.502448ms","start":"2026-04-07T01:25:25.911784Z","end":"2026-04-07T01:25:26.078286Z","steps":["trace[1302887980] 'process raft request' (duration: 166.365075ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:26.086542Z","caller":"traceutil/trace.go:171","msg":"trace[822425854] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"155.022981ms","start":"2026-04-07T01:25:25.931477Z","end":"2026-04-07T01:25:26.0865Z","steps":["trace[822425854] 'process raft request' (duration: 154.817276ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:26.973177Z","caller":"traceutil/trace.go:171","msg":"trace[845511093] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"451.360849ms","start":"2026-04-07T01:25:26.521786Z","end":"2026-04-07T01:25:26.973147Z","steps":["trace[845511093] 'process raft request' (duration: 450.116263ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:26.973201Z","caller":"traceutil/trace.go:171","msg":"trace[1350553392] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"199.57465ms","start":"2026-04-07T01:25:26.773599Z","end":"2026-04-07T01:25:26.973174Z","steps":["trace[1350553392] 'read index received' (duration: 197.875565ms)","trace[1350553392] 'applied index is now lower than readState.Index' (duration: 1.696765ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:25:26.973329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.710253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:25:26.973361Z","caller":"traceutil/trace.go:171","msg":"trace[1428094574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"199.759384ms","start":"2026-04-07T01:25:26.773592Z","end":"2026-04-07T01:25:26.973351Z","steps":["trace[1428094574] 'agreement among raft nodes before linearized reading' (duration: 199.676022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:26.977759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:26.521751Z","time spent":"451.508232ms","remote":"127.0.0.1:58002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:25:48.772749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.005327ms","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-07T01:25:48.772859Z","caller":"traceutil/trace.go:171","msg":"trace[1325134201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"133.16883ms","start":"2026-04-07T01:25:48.63967Z","end":"2026-04-07T01:25:48.772839Z","steps":["trace[1325134201] 'range keys from in-memory index tree' (duration: 132.713101ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:37.469814Z","caller":"traceutil/trace.go:171","msg":"trace[1438843276] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"133.036188ms","start":"2026-04-07T01:26:37.336747Z","end":"2026-04-07T01:26:37.469783Z","steps":["trace[1438843276] 'process raft request' (duration: 123.000858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:00.58346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.067691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-21c46c5c-a581-436e-977c-82427a4aeba0\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-07T01:27:00.583582Z","caller":"traceutil/trace.go:171","msg":"trace[1342578585] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-21c46c5c-a581-436e-977c-82427a4aeba0; range_end:; response_count:1; response_revision:1848; }","duration":"179.308166ms","start":"2026-04-07T01:27:00.404248Z","end":"2026-04-07T01:27:00.583556Z","steps":["trace[1342578585] 'range keys from in-memory index tree' (duration: 178.814086ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:00.779908Z","caller":"traceutil/trace.go:171","msg":"trace[1655472549] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"133.351766ms","start":"2026-04-07T01:27:00.646514Z","end":"2026-04-07T01:27:00.779866Z","steps":["trace[1655472549] 'process raft request' (duration: 69.325348ms)","trace[1655472549] 'compare' (duration: 63.746572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:01.059769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.299823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T01:27:01.059884Z","caller":"traceutil/trace.go:171","msg":"trace[2097802210] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1851; }","duration":"142.494757ms","start":"2026-04-07T01:27:00.917365Z","end":"2026-04-07T01:27:01.05986Z","steps":["trace[2097802210] 'range keys from in-memory index tree' (duration: 142.022327ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:51.058718Z","caller":"traceutil/trace.go:171","msg":"trace[673290718] linearizableReadLoop","detail":"{readStateIndex:2262; appliedIndex:2261; }","duration":"157.988172ms","start":"2026-04-07T01:27:50.900703Z","end":"2026-04-07T01:27:51.058691Z","steps":["trace[673290718] 'read index received' (duration: 157.767988ms)","trace[673290718] 'applied index is now lower than readState.Index' (duration: 218.974µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:51.058929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.196456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-07T01:27:51.059022Z","caller":"traceutil/trace.go:171","msg":"trace[568393263] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2177; }","duration":"158.33653ms","start":"2026-04-07T01:27:50.900668Z","end":"2026-04-07T01:27:51.059005Z","steps":["trace[568393263] 'agreement among raft nodes before linearized reading' (duration: 158.148956ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:51.058886Z","caller":"traceutil/trace.go:171","msg":"trace[259108949] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"159.098206ms","start":"2026-04-07T01:27:50.899691Z","end":"2026-04-07T01:27:51.05879Z","steps":["trace[259108949] 'process raft request' (duration: 158.841731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:51.324772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.696154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:27:51.325Z","caller":"traceutil/trace.go:171","msg":"trace[762456679] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"241.780079ms","start":"2026-04-07T01:27:51.083121Z","end":"2026-04-07T01:27:51.324902Z","steps":["trace[762456679] 'process raft request' (duration: 76.594185ms)","trace[762456679] 'compare' (duration: 164.368807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:51.595439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.391752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T01:27:51.595718Z","caller":"traceutil/trace.go:171","msg":"trace[2006593050] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2178; }","duration":"149.720589ms","start":"2026-04-07T01:27:51.445975Z","end":"2026-04-07T01:27:51.595696Z","steps":["trace[2006593050] 'range keys from in-memory index tree' (duration: 148.977492ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:28:40.310413Z","caller":"traceutil/trace.go:171","msg":"trace[97786829] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"113.432809ms","start":"2026-04-07T01:28:40.196948Z","end":"2026-04-07T01:28:40.310381Z","steps":["trace[97786829] 'process raft request' (duration: 113.073151ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:29:15.01491Z","caller":"traceutil/trace.go:171","msg":"trace[397318606] linearizableReadLoop","detail":"{readStateIndex:2939; appliedIndex:2938; }","duration":"143.883208ms","start":"2026-04-07T01:29:14.871001Z","end":"2026-04-07T01:29:15.014884Z","steps":["trace[397318606] 'read index received' (duration: 143.632383ms)","trace[397318606] 'applied index is now lower than readState.Index' (duration: 249.905µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:29:15.015026Z","caller":"traceutil/trace.go:171","msg":"trace[264415435] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"224.189241ms","start":"2026-04-07T01:29:14.790792Z","end":"2026-04-07T01:29:15.014981Z","steps":["trace[264415435] 'process raft request' (duration: 223.901845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:29:15.015074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.052621ms","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-07T01:29:15.015137Z","caller":"traceutil/trace.go:171","msg":"trace[879142208] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2828; }","duration":"144.149593ms","start":"2026-04-07T01:29:14.870959Z","end":"2026-04-07T01:29:15.015108Z","steps":["trace[879142208] 'agreement among raft nodes before linearized reading' (duration: 144.048841ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:31:39.815339Z","caller":"traceutil/trace.go:171","msg":"trace[1597104113] transaction","detail":"{read_only:false; response_revision:3791; number_of_response:1; }","duration":"130.917594ms","start":"2026-04-07T01:31:39.684382Z","end":"2026-04-07T01:31:39.815299Z","steps":["trace[1597104113] 'process raft request' (duration: 130.598887ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:31:39.956997Z","caller":"traceutil/trace.go:171","msg":"trace[2075118873] transaction","detail":"{read_only:false; response_revision:3792; number_of_response:1; }","duration":"137.426051ms","start":"2026-04-07T01:31:39.819536Z","end":"2026-04-07T01:31:39.956962Z","steps":["trace[2075118873] 'process raft request' (duration: 133.032279ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:06.424916Z","caller":"traceutil/trace.go:171","msg":"trace[116666021] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"151.347029ms","start":"2026-04-07T01:33:06.273532Z","end":"2026-04-07T01:33:06.424879Z","steps":["trace[116666021] 'process raft request' (duration: 151.045003ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:23.06624Z","caller":"traceutil/trace.go:171","msg":"trace[1723882099] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"216.162519ms","start":"2026-04-07T01:33:22.850048Z","end":"2026-04-07T01:33:23.066211Z","steps":["trace[1723882099] 'process raft request' (duration: 138.927077ms)","trace[1723882099] 'compare' (duration: 77.007117ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:33:23.066578Z","caller":"traceutil/trace.go:171","msg":"trace[84781369] linearizableReadLoop","detail":"{readStateIndex:5502; appliedIndex:5500; }","duration":"124.086333ms","start":"2026-04-07T01:33:22.942468Z","end":"2026-04-07T01:33:23.066555Z","steps":["trace[84781369] 'read index received' (duration: 46.526924ms)","trace[84781369] 'applied index is now lower than readState.Index' (duration: 77.557549ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:33:23.066666Z","caller":"traceutil/trace.go:171","msg":"trace[1767471057] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"188.207309ms","start":"2026-04-07T01:33:22.878408Z","end":"2026-04-07T01:33:23.066615Z","steps":["trace[1767471057] 'process raft request' (duration: 187.754809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:33:23.066855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.376559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T01:33:23.066887Z","caller":"traceutil/trace.go:171","msg":"trace[1906907434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5303; }","duration":"124.465881ms","start":"2026-04-07T01:33:22.942411Z","end":"2026-04-07T01:33:23.066876Z","steps":["trace[1906907434] 'agreement among raft nodes before linearized reading' (duration: 124.227706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:33:23.360468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.975867ms","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-07T01:33:23.360564Z","caller":"traceutil/trace.go:171","msg":"trace[1729706134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5304; }","duration":"147.113799ms","start":"2026-04-07T01:33:23.213431Z","end":"2026-04-07T01:33:23.360545Z","steps":["trace[1729706134] 'range keys from in-memory index tree' (duration: 146.693321ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:38.320164Z","caller":"traceutil/trace.go:171","msg":"trace[103356330] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"174.849316ms","start":"2026-04-07T01:33:38.145295Z","end":"2026-04-07T01:33:38.320144Z","steps":["trace[103356330] 'process raft request' (duration: 174.708383ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:38.759549Z","caller":"traceutil/trace.go:171","msg":"trace[377673360] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"103.367016ms","start":"2026-04-07T01:33:38.656155Z","end":"2026-04-07T01:33:38.759522Z","steps":["trace[377673360] 'process raft request' (duration: 103.154161ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:38.759739Z","caller":"traceutil/trace.go:171","msg":"trace[1477825159] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"102.327463ms","start":"2026-04-07T01:33:38.657398Z","end":"2026-04-07T01:33:38.759725Z","steps":["trace[1477825159] 'process raft request' (duration: 102.064867ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:38.759979Z","caller":"traceutil/trace.go:171","msg":"trace[1117335216] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"102.301642ms","start":"2026-04-07T01:33:38.65765Z","end":"2026-04-07T01:33:38.759952Z","steps":["trace[1117335216] 'process raft request' (duration: 102.018577ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:22.180303Z","caller":"traceutil/trace.go:171","msg":"trace[1510168635] transaction","detail":"{read_only:false; response_revision:5911; number_of_response:1; }","duration":"143.636445ms","start":"2026-04-07T01:34:22.036651Z","end":"2026-04-07T01:34:22.180288Z","steps":["trace[1510168635] 'process raft request' (duration: 100.171314ms)","trace[1510168635] 'compare' (duration: 43.237257ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:34:22.180388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.334784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:34:22.180263Z","caller":"traceutil/trace.go:171","msg":"trace[1369806076] linearizableReadLoop","detail":"{readStateIndex:6128; appliedIndex:6127; }","duration":"105.193401ms","start":"2026-04-07T01:34:22.075036Z","end":"2026-04-07T01:34:22.18023Z","steps":["trace[1369806076] 'read index received' (duration: 61.801401ms)","trace[1369806076] 'applied index is now lower than readState.Index' (duration: 43.39106ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:34:22.18043Z","caller":"traceutil/trace.go:171","msg":"trace[766334682] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5911; }","duration":"105.425586ms","start":"2026-04-07T01:34:22.074996Z","end":"2026-04-07T01:34:22.180422Z","steps":["trace[766334682] 'agreement among raft nodes before linearized reading' (duration: 105.338924ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:49.656196Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3017} {"level":"info","ts":"2026-04-07T01:34:49.741933Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3017,"took":"84.095633ms","hash":799292198,"current-db-size-bytes":58032128,"current-db-size":"58 MB","current-db-size-in-use-bytes":31936512,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T01:34:49.742016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":799292198,"revision":3017,"compact-revision":-1} {"level":"warn","ts":"2026-04-07T01:39:11.144858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.875378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:39:11.145087Z","caller":"traceutil/trace.go:171","msg":"trace[773177563] transaction","detail":"{read_only:false; response_revision:8101; number_of_response:1; }","duration":"418.57694ms","start":"2026-04-07T01:39:10.726472Z","end":"2026-04-07T01:39:11.145049Z","steps":["trace[773177563] 'process raft request' (duration: 263.341284ms)","trace[773177563] 'compare' (duration: 154.624963ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:39:11.145318Z","caller":"traceutil/trace.go:171","msg":"trace[897834412] transaction","detail":"{read_only:false; response_revision:8103; number_of_response:1; }","duration":"246.399486ms","start":"2026-04-07T01:39:10.898887Z","end":"2026-04-07T01:39:11.145287Z","steps":["trace[897834412] 'process raft request' (duration: 246.261573ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:11.145351Z","caller":"traceutil/trace.go:171","msg":"trace[1256137060] linearizableReadLoop","detail":"{readStateIndex:8386; appliedIndex:8385; }","duration":"408.555567ms","start":"2026-04-07T01:39:10.736758Z","end":"2026-04-07T01:39:11.145314Z","steps":["trace[1256137060] 'read index received' (duration: 253.260871ms)","trace[1256137060] 'applied index is now lower than readState.Index' (duration: 155.292736ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:39:11.145386Z","caller":"traceutil/trace.go:171","msg":"trace[1111147500] transaction","detail":"{read_only:false; response_revision:8102; number_of_response:1; }","duration":"247.61265ms","start":"2026-04-07T01:39:10.897745Z","end":"2026-04-07T01:39:11.145358Z","steps":["trace[1111147500] 'process raft request' (duration: 247.299584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:11.145562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.771713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T01:39:11.145566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:10.726418Z","time spent":"418.754043ms","remote":"127.0.0.1:58174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:39:11.145659Z","caller":"traceutil/trace.go:171","msg":"trace[403060139] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8103; }","duration":"408.914856ms","start":"2026-04-07T01:39:10.736711Z","end":"2026-04-07T01:39:11.145626Z","steps":["trace[403060139] 'agreement among raft nodes before linearized reading' (duration: 408.733301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:11.145715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:10.73669Z","time spent":"409.014727ms","remote":"127.0.0.1:34638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-07T01:39:11.1544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.218199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:39:11.15444Z","caller":"traceutil/trace.go:171","msg":"trace[1478887142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8104; }","duration":"381.337012ms","start":"2026-04-07T01:39:10.773094Z","end":"2026-04-07T01:39:11.154431Z","steps":["trace[1478887142] 'agreement among raft nodes before linearized reading' (duration: 381.22001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:11.15446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:10.773077Z","time spent":"381.379153ms","remote":"127.0.0.1:57920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T01:39:11.154622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.137093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19444"} {"level":"info","ts":"2026-04-07T01:39:11.154647Z","caller":"traceutil/trace.go:171","msg":"trace[2070289145] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8104; }","duration":"120.199864ms","start":"2026-04-07T01:39:11.034441Z","end":"2026-04-07T01:39:11.154641Z","steps":["trace[2070289145] 'agreement among raft nodes before linearized reading' (duration: 120.139923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:11.154669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.525604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:39:11.154752Z","caller":"traceutil/trace.go:171","msg":"trace[1540974624] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8104; }","duration":"157.661337ms","start":"2026-04-07T01:39:10.997073Z","end":"2026-04-07T01:39:11.154734Z","steps":["trace[1540974624] 'agreement among raft nodes before linearized reading' (duration: 157.516274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:22.900087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.82372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:39:22.9002Z","caller":"traceutil/trace.go:171","msg":"trace[1485051352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8161; }","duration":"121.979202ms","start":"2026-04-07T01:39:22.778196Z","end":"2026-04-07T01:39:22.900175Z","steps":["trace[1485051352] 'range keys from in-memory index tree' (duration: 121.711046ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:23.5024Z","caller":"traceutil/trace.go:171","msg":"trace[1916231767] linearizableReadLoop","detail":"{readStateIndex:8452; appliedIndex:8450; }","duration":"305.158319ms","start":"2026-04-07T01:39:23.19722Z","end":"2026-04-07T01:39:23.502379Z","steps":["trace[1916231767] 'read index received' (duration: 5.636499ms)","trace[1916231767] 'applied index is now lower than readState.Index' (duration: 299.52102ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:39:23.50248Z","caller":"traceutil/trace.go:171","msg":"trace[399847801] transaction","detail":"{read_only:false; response_revision:8164; number_of_response:1; }","duration":"309.4531ms","start":"2026-04-07T01:39:23.192987Z","end":"2026-04-07T01:39:23.50244Z","steps":["trace[399847801] 'process raft request' (duration: 309.263885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:23.502622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.648127ms","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-07T01:39:23.502653Z","caller":"traceutil/trace.go:171","msg":"trace[1036708404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8164; }","duration":"294.720618ms","start":"2026-04-07T01:39:23.207925Z","end":"2026-04-07T01:39:23.502645Z","steps":["trace[1036708404] 'agreement among raft nodes before linearized reading' (duration: 294.560745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:23.502661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.207893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:39:23.50269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.452196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T01:39:23.502746Z","caller":"traceutil/trace.go:171","msg":"trace[1171886304] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8164; }","duration":"230.295055ms","start":"2026-04-07T01:39:23.272429Z","end":"2026-04-07T01:39:23.502724Z","steps":["trace[1171886304] 'agreement among raft nodes before linearized reading' (duration: 230.136312ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:23.50278Z","caller":"traceutil/trace.go:171","msg":"trace[1788644428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8164; }","duration":"305.598358ms","start":"2026-04-07T01:39:23.197164Z","end":"2026-04-07T01:39:23.502762Z","steps":["trace[1788644428] 'agreement among raft nodes before linearized reading' (duration: 305.321322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:23.502707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:23.192979Z","time spent":"309.594794ms","remote":"127.0.0.1:58174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:39:23.502842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:23.197142Z","time spent":"305.69083ms","remote":"127.0.0.1:58174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T01:39:24.458432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.568568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-07T01:39:24.458514Z","caller":"traceutil/trace.go:171","msg":"trace[1172081094] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8169; }","duration":"168.69659ms","start":"2026-04-07T01:39:24.289799Z","end":"2026-04-07T01:39:24.458496Z","steps":["trace[1172081094] 'range keys from in-memory index tree' (duration: 168.381674ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:24.483454Z","caller":"traceutil/trace.go:171","msg":"trace[776499718] linearizableReadLoop","detail":"{readStateIndex:8458; appliedIndex:8457; }","duration":"104.172944ms","start":"2026-04-07T01:39:24.379261Z","end":"2026-04-07T01:39:24.483434Z","steps":["trace[776499718] 'read index received' (duration: 104.076352ms)","trace[776499718] 'applied index is now lower than readState.Index' (duration: 95.842µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:39:24.48355Z","caller":"traceutil/trace.go:171","msg":"trace[1935576434] transaction","detail":"{read_only:false; response_revision:8170; number_of_response:1; }","duration":"149.026624ms","start":"2026-04-07T01:39:24.334497Z","end":"2026-04-07T01:39:24.483523Z","steps":["trace[1935576434] 'process raft request' (duration: 148.789249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:24.483605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.316377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:39:24.483647Z","caller":"traceutil/trace.go:171","msg":"trace[1438692594] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8170; }","duration":"104.429459ms","start":"2026-04-07T01:39:24.379204Z","end":"2026-04-07T01:39:24.483634Z","steps":["trace[1438692594] 'agreement among raft nodes before linearized reading' (duration: 104.344428ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:49.662611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6136} {"level":"info","ts":"2026-04-07T01:39:49.757338Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6136,"took":"92.270933ms","hash":2981640370,"current-db-size-bytes":58032128,"current-db-size":"58 MB","current-db-size-in-use-bytes":23302144,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-07T01:39:49.757411Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2981640370,"revision":6136,"compact-revision":3017} {"level":"info","ts":"2026-04-07T01:39:51.636799Z","caller":"traceutil/trace.go:171","msg":"trace[509890044] transaction","detail":"{read_only:false; response_revision:8368; number_of_response:1; }","duration":"214.735125ms","start":"2026-04-07T01:39:51.422049Z","end":"2026-04-07T01:39:51.636784Z","steps":["trace[509890044] 'process raft request' (duration: 214.393807ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:51.844027Z","caller":"traceutil/trace.go:171","msg":"trace[1177309679] linearizableReadLoop","detail":"{readStateIndex:8667; appliedIndex:8666; }","duration":"164.792938ms","start":"2026-04-07T01:39:51.679196Z","end":"2026-04-07T01:39:51.843989Z","steps":["trace[1177309679] 'read index received' (duration: 164.617034ms)","trace[1177309679] 'applied index is now lower than readState.Index' (duration: 171.793µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:39:51.844056Z","caller":"traceutil/trace.go:171","msg":"trace[366938882] transaction","detail":"{read_only:false; response_revision:8370; number_of_response:1; }","duration":"205.145272ms","start":"2026-04-07T01:39:51.638883Z","end":"2026-04-07T01:39:51.844028Z","steps":["trace[366938882] 'process raft request' (duration: 204.920518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:51.844467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.821883ms","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-07T01:39:51.844818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.599545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T01:39:51.844878Z","caller":"traceutil/trace.go:171","msg":"trace[1121066291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8370; }","duration":"165.720897ms","start":"2026-04-07T01:39:51.679143Z","end":"2026-04-07T01:39:51.844864Z","steps":["trace[1121066291] 'agreement among raft nodes before linearized reading' (duration: 165.047303ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:51.844841Z","caller":"traceutil/trace.go:171","msg":"trace[437601256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8370; }","duration":"160.239341ms","start":"2026-04-07T01:39:51.68458Z","end":"2026-04-07T01:39:51.844819Z","steps":["trace[437601256] 'agreement among raft nodes before linearized reading' (duration: 159.72009ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:04.252115Z","caller":"traceutil/trace.go:171","msg":"trace[730369614] linearizableReadLoop","detail":"{readStateIndex:8783; appliedIndex:8782; }","duration":"213.115921ms","start":"2026-04-07T01:40:04.038979Z","end":"2026-04-07T01:40:04.252095Z","steps":["trace[730369614] 'read index received' (duration: 212.918107ms)","trace[730369614] 'applied index is now lower than readState.Index' (duration: 196.924µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:40:04.252179Z","caller":"traceutil/trace.go:171","msg":"trace[414285206] transaction","detail":"{read_only:false; response_revision:8480; number_of_response:1; }","duration":"214.261806ms","start":"2026-04-07T01:40:04.037882Z","end":"2026-04-07T01:40:04.252143Z","steps":["trace[414285206] 'process raft request' (duration: 214.081932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:04.252322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.326695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-qpzmf\" ","response":"range_response_count:1 size:2802"} {"level":"info","ts":"2026-04-07T01:40:04.252358Z","caller":"traceutil/trace.go:171","msg":"trace[242851497] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-qpzmf; range_end:; response_count:1; response_revision:8480; }","duration":"213.404637ms","start":"2026-04-07T01:40:04.038941Z","end":"2026-04-07T01:40:04.252346Z","steps":["trace[242851497] 'agreement among raft nodes before linearized reading' (duration: 213.265444ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:04.845262Z","caller":"traceutil/trace.go:171","msg":"trace[376435161] linearizableReadLoop","detail":"{readStateIndex:8787; appliedIndex:8786; }","duration":"148.459622ms","start":"2026-04-07T01:40:04.696774Z","end":"2026-04-07T01:40:04.845233Z","steps":["trace[376435161] 'read index received' (duration: 57.017206ms)","trace[376435161] 'applied index is now lower than readState.Index' (duration: 91.441036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:40:04.845506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.687156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qpzmf\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-04-07T01:40:04.845547Z","caller":"traceutil/trace.go:171","msg":"trace[801671244] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qpzmf; range_end:; response_count:1; response_revision:8484; }","duration":"148.819749ms","start":"2026-04-07T01:40:04.696717Z","end":"2026-04-07T01:40:04.845536Z","steps":["trace[801671244] 'agreement among raft nodes before linearized reading' (duration: 148.627205ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:04.845677Z","caller":"traceutil/trace.go:171","msg":"trace[1415840517] transaction","detail":"{read_only:false; response_revision:8484; number_of_response:1; }","duration":"205.909648ms","start":"2026-04-07T01:40:04.639737Z","end":"2026-04-07T01:40:04.845646Z","steps":["trace[1415840517] 'process raft request' (duration: 113.985863ms)","trace[1415840517] 'compare' (duration: 91.380884ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:40:04.934163Z","caller":"traceutil/trace.go:171","msg":"trace[1430672604] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"231.864196ms","start":"2026-04-07T01:40:04.702246Z","end":"2026-04-07T01:40:04.93411Z","steps":["trace[1430672604] 'process raft request' (duration: 231.741994ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:04.934628Z","caller":"traceutil/trace.go:171","msg":"trace[1743529188] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"237.000897ms","start":"2026-04-07T01:40:04.697609Z","end":"2026-04-07T01:40:04.93461Z","steps":["trace[1743529188] 'process raft request' (duration: 236.27656ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:05.733792Z","caller":"traceutil/trace.go:171","msg":"trace[692393595] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"212.913216ms","start":"2026-04-07T01:40:05.520852Z","end":"2026-04-07T01:40:05.733765Z","steps":["trace[692393595] 'process raft request' (duration: 212.752863ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:05.87468Z","caller":"traceutil/trace.go:171","msg":"trace[503450921] linearizableReadLoop","detail":"{readStateIndex:8796; appliedIndex:8794; }","duration":"120.816377ms","start":"2026-04-07T01:40:05.753841Z","end":"2026-04-07T01:40:05.874657Z","steps":["trace[503450921] 'read index received' (duration: 106.510505ms)","trace[503450921] 'applied index is now lower than readState.Index' (duration: 14.304852ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:40:05.874715Z","caller":"traceutil/trace.go:171","msg":"trace[469355088] transaction","detail":"{read_only:false; response_revision:8492; number_of_response:1; }","duration":"181.508742ms","start":"2026-04-07T01:40:05.693182Z","end":"2026-04-07T01:40:05.874691Z","steps":["trace[469355088] 'process raft request' (duration: 167.311351ms)","trace[469355088] 'compare' (duration: 14.015197ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:40:05.874762Z","caller":"traceutil/trace.go:171","msg":"trace[269367417] transaction","detail":"{read_only:false; response_revision:8493; number_of_response:1; }","duration":"169.553408ms","start":"2026-04-07T01:40:05.705176Z","end":"2026-04-07T01:40:05.87473Z","steps":["trace[269367417] 'process raft request' (duration: 169.437965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:05.874867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.98692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:40:05.874975Z","caller":"traceutil/trace.go:171","msg":"trace[404645135] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:8493; }","duration":"121.140293ms","start":"2026-04-07T01:40:05.753811Z","end":"2026-04-07T01:40:05.874952Z","steps":["trace[404645135] 'agreement among raft nodes before linearized reading' (duration: 120.947799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:05.874972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.736173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:40:05.875083Z","caller":"traceutil/trace.go:171","msg":"trace[738794066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8493; }","duration":"101.929857ms","start":"2026-04-07T01:40:05.773141Z","end":"2026-04-07T01:40:05.875071Z","steps":["trace[738794066] 'agreement among raft nodes before linearized reading' (duration: 101.731562ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:51.150637Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002452.wal"} {"level":"info","ts":"2026-04-07T01:41:12.236423Z","caller":"traceutil/trace.go:171","msg":"trace[1221484855] transaction","detail":"{read_only:false; response_revision:9124; number_of_response:1; }","duration":"117.705791ms","start":"2026-04-07T01:41:12.118691Z","end":"2026-04-07T01:41:12.236397Z","steps":["trace[1221484855] 'process raft request' (duration: 117.548228ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:42:14.634868Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:42:14.640194Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T01:42:14.640276Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T01:44:49.668642Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8360} {"level":"info","ts":"2026-04-07T01:44:49.746227Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8360,"took":"75.358558ms","hash":3963447841,"current-db-size-bytes":58032128,"current-db-size":"58 MB","current-db-size-in-use-bytes":24915968,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-07T01:44:49.746296Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3963447841,"revision":8360,"compact-revision":6136} {"level":"info","ts":"2026-04-07T01:49:49.678686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10746} {"level":"info","ts":"2026-04-07T01:49:49.758365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10746,"took":"77.577872ms","hash":1359435657,"current-db-size-bytes":58032128,"current-db-size":"58 MB","current-db-size-in-use-bytes":20377600,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-07T01:49:49.758439Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1359435657,"revision":10746,"compact-revision":8360}