{"level":"warn","ts":"2026-04-16T11:43:05.834348Z","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-16T11:43:05.834449Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-16T11:43:05.83453Z","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-16T11:43:05.834544Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-04-16T11:43:05.834569Z","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-16T11:43:05.835381Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-04-16T11:43:05.835477Z","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":8,"max-cpu-available":8,"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.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-16T11:43:05.839624Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.797758ms"} {"level":"info","ts":"2026-04-16T11:43:05.846892Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-04-16T11:43:05.846958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T11:43:05.846976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-04-16T11:43:05.846983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T11:43:05.84699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-04-16T11:43:05.847018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-04-16T11:43:05.856832Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T11:43:05.858552Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T11:43:05.859638Z","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-16T11:43:05.86155Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T11:43:05.861822Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T11:43:05.861894Z","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-16T11:43:05.861946Z","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-16T11:43:05.861961Z","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-16T11:43:05.865899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-04-16T11:43:05.866057Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-04-16T11:43:05.867204Z","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-16T11:43:05.867401Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T11:43:05.867394Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-04-16T11:43:05.867488Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-04-16T11:43:05.867493Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T11:43:06.847321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T11:43:06.847541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T11:43:06.847611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-04-16T11:43:06.847696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-04-16T11:43:06.847757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-04-16T11:43:06.847832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-04-16T11:43:06.847884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-04-16T11:43:06.849082Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T11:43:06.849084Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T11:43:06.849418Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T11:43:06.849475Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T11:43:06.849128Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T11:43:06.849274Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T11:43:06.852589Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T11:43:06.854665Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-04-16T11:43:06.854748Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T11:43:06.855186Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T11:43:06.855259Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T11:43:39.057366Z","caller":"traceutil/trace.go:171","msg":"trace[253213158] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"108.296353ms","start":"2026-04-16T11:43:38.949028Z","end":"2026-04-16T11:43:39.057325Z","steps":["trace[253213158] 'read index received' (duration: 107.223829ms)","trace[253213158] 'applied index is now lower than readState.Index' (duration: 1.070734ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:43:39.057436Z","caller":"traceutil/trace.go:171","msg":"trace[218556188] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"171.091562ms","start":"2026-04-16T11:43:38.886305Z","end":"2026-04-16T11:43:39.057397Z","steps":["trace[218556188] 'process raft request' (duration: 170.272662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:43:39.057774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.689678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T11:43:39.057876Z","caller":"traceutil/trace.go:171","msg":"trace[1373543018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"108.8929ms","start":"2026-04-16T11:43:38.948964Z","end":"2026-04-16T11:43:39.057857Z","steps":["trace[1373543018] 'agreement among raft nodes before linearized reading' (duration: 108.521195ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:43:39.099917Z","caller":"traceutil/trace.go:171","msg":"trace[1552281378] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"108.673156ms","start":"2026-04-16T11:43:38.991185Z","end":"2026-04-16T11:43:39.099858Z","steps":["trace[1552281378] 'process raft request' (duration: 108.415173ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:43:39.643179Z","caller":"traceutil/trace.go:171","msg":"trace[1649294816] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"475.824629ms","start":"2026-04-16T11:43:39.167313Z","end":"2026-04-16T11:43:39.643137Z","steps":["trace[1649294816] 'process raft request' (duration: 475.627767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:43:39.643942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:43:39.167291Z","time spent":"475.975561ms","remote":"127.0.0.1:59476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:44:03.021595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.943154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:44:03.0219Z","caller":"traceutil/trace.go:171","msg":"trace[1559420867] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"310.037556ms","start":"2026-04-16T11:44:02.711829Z","end":"2026-04-16T11:44:03.021867Z","steps":["trace[1559420867] 'process raft request' (duration: 309.924864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:03.021975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:02.711805Z","time spent":"310.138987ms","remote":"127.0.0.1:59554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:03.021934Z","caller":"traceutil/trace.go:171","msg":"trace[497113514] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"310.185727ms","start":"2026-04-16T11:44:02.711713Z","end":"2026-04-16T11:44:03.021899Z","steps":["trace[497113514] 'process raft request' (duration: 97.649845ms)","trace[497113514] 'compare' (duration: 211.350188ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:44:03.022214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:02.711694Z","time spent":"310.42503ms","remote":"127.0.0.1:59554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:37.533119Z","caller":"traceutil/trace.go:171","msg":"trace[1290715145] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"103.077988ms","start":"2026-04-16T11:44:37.430024Z","end":"2026-04-16T11:44:37.533102Z","steps":["trace[1290715145] 'process raft request' (duration: 102.965317ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:45:04.212018Z","caller":"traceutil/trace.go:171","msg":"trace[569519864] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"120.981299ms","start":"2026-04-16T11:45:04.091017Z","end":"2026-04-16T11:45:04.211999Z","steps":["trace[569519864] 'process raft request' (duration: 120.863918ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:45:09.964073Z","caller":"traceutil/trace.go:171","msg":"trace[914922352] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1156; }","duration":"274.163666ms","start":"2026-04-16T11:45:09.68989Z","end":"2026-04-16T11:45:09.964054Z","steps":["trace[914922352] 'read index received' (duration: 274.031725ms)","trace[914922352] 'applied index is now lower than readState.Index' (duration: 131.281µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:45:09.964142Z","caller":"traceutil/trace.go:171","msg":"trace[1603044373] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"324.811437ms","start":"2026-04-16T11:45:09.639288Z","end":"2026-04-16T11:45:09.964099Z","steps":["trace[1603044373] 'process raft request' (duration: 324.643316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:45:09.964249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.353867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T11:45:09.96428Z","caller":"traceutil/trace.go:171","msg":"trace[1453890609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1112; }","duration":"274.424248ms","start":"2026-04-16T11:45:09.689849Z","end":"2026-04-16T11:45:09.964273Z","steps":["trace[1453890609] 'agreement among raft nodes before linearized reading' (duration: 274.313377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:45:09.964296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:45:09.639265Z","time spent":"324.944928ms","remote":"127.0.0.1:59554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:45:09.964346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.231382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:45:09.964435Z","caller":"traceutil/trace.go:171","msg":"trace[1909262509] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1112; }","duration":"140.378354ms","start":"2026-04-16T11:45:09.824037Z","end":"2026-04-16T11:45:09.964416Z","steps":["trace[1909262509] 'agreement among raft nodes before linearized reading' (duration: 140.213093ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:45:34.172128Z","caller":"traceutil/trace.go:171","msg":"trace[722461999] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1241; }","duration":"114.421537ms","start":"2026-04-16T11:45:34.057687Z","end":"2026-04-16T11:45:34.172108Z","steps":["trace[722461999] 'read index received' (duration: 114.290896ms)","trace[722461999] 'applied index is now lower than readState.Index' (duration: 129.901µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:45:34.172342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.658069ms","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-16T11:45:34.17231Z","caller":"traceutil/trace.go:171","msg":"trace[502309244] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"133.7218ms","start":"2026-04-16T11:45:34.038555Z","end":"2026-04-16T11:45:34.172277Z","steps":["trace[502309244] 'process raft request' (duration: 133.419588ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:45:34.172429Z","caller":"traceutil/trace.go:171","msg":"trace[1956602946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1192; }","duration":"114.798021ms","start":"2026-04-16T11:45:34.05762Z","end":"2026-04-16T11:45:34.172418Z","steps":["trace[1956602946] 'agreement among raft nodes before linearized reading' (duration: 114.635479ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:45:34.922688Z","caller":"traceutil/trace.go:171","msg":"trace[1511058256] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"150.184178ms","start":"2026-04-16T11:45:34.772478Z","end":"2026-04-16T11:45:34.922662Z","steps":["trace[1511058256] 'process raft request' (duration: 150.026166ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:49:35.117615Z","caller":"traceutil/trace.go:171","msg":"trace[115916311] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"144.039296ms","start":"2026-04-16T11:49:34.973549Z","end":"2026-04-16T11:49:35.117588Z","steps":["trace[115916311] 'process raft request' (duration: 143.882113ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:00.12513Z","caller":"traceutil/trace.go:171","msg":"trace[1268947523] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"141.896273ms","start":"2026-04-16T11:49:59.983208Z","end":"2026-04-16T11:50:00.125104Z","steps":["trace[1268947523] 'process raft request' (duration: 141.75698ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:00.81945Z","caller":"traceutil/trace.go:171","msg":"trace[1127274361] linearizableReadLoop","detail":"{readStateIndex:2174; appliedIndex:2173; }","duration":"100.840086ms","start":"2026-04-16T11:50:00.718583Z","end":"2026-04-16T11:50:00.819423Z","steps":["trace[1127274361] 'read index received' (duration: 83.840288ms)","trace[1127274361] 'applied index is now lower than readState.Index' (duration: 16.998278ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:50:00.819607Z","caller":"traceutil/trace.go:171","msg":"trace[2034460603] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"101.491389ms","start":"2026-04-16T11:50:00.718101Z","end":"2026-04-16T11:50:00.819592Z","steps":["trace[2034460603] 'process raft request' (duration: 84.413409ms)","trace[2034460603] 'compare' (duration: 16.634951ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:50:00.819859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.252135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T11:50:00.819933Z","caller":"traceutil/trace.go:171","msg":"trace[398290528] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2070; }","duration":"101.364507ms","start":"2026-04-16T11:50:00.718554Z","end":"2026-04-16T11:50:00.819918Z","steps":["trace[398290528] 'agreement among raft nodes before linearized reading' (duration: 101.174973ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:12.931432Z","caller":"traceutil/trace.go:171","msg":"trace[1086519507] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"176.127664ms","start":"2026-04-16T11:50:12.755275Z","end":"2026-04-16T11:50:12.931403Z","steps":["trace[1086519507] 'process raft request' (duration: 175.93671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:13.127346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.176649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:50:13.127694Z","caller":"traceutil/trace.go:171","msg":"trace[378256725] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"192.899547ms","start":"2026-04-16T11:50:12.934767Z","end":"2026-04-16T11:50:13.127667Z","steps":["trace[378256725] 'process raft request' (duration: 55.327931ms)","trace[378256725] 'compare' (duration: 137.070837ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:50:13.127787Z","caller":"traceutil/trace.go:171","msg":"trace[1031697853] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"192.5199ms","start":"2026-04-16T11:50:12.935245Z","end":"2026-04-16T11:50:13.127765Z","steps":["trace[1031697853] 'process raft request' (duration: 192.222324ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:29.672479Z","caller":"traceutil/trace.go:171","msg":"trace[1248484578] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"286.097206ms","start":"2026-04-16T11:50:29.386346Z","end":"2026-04-16T11:50:29.672443Z","steps":["trace[1248484578] 'process raft request' (duration: 285.916012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:30.092348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.75768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:50:30.092417Z","caller":"traceutil/trace.go:171","msg":"trace[230595481] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:2237; }","duration":"109.873773ms","start":"2026-04-16T11:50:29.982531Z","end":"2026-04-16T11:50:30.092404Z","steps":["trace[230595481] 'range keys from in-memory index tree' (duration: 109.661469ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:30.272426Z","caller":"traceutil/trace.go:171","msg":"trace[1553495518] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"162.919234ms","start":"2026-04-16T11:50:30.10947Z","end":"2026-04-16T11:50:30.272389Z","steps":["trace[1553495518] 'process raft request' (duration: 136.866249ms)","trace[1553495518] 'compare' (duration: 25.881122ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:50:52.58824Z","caller":"traceutil/trace.go:171","msg":"trace[104874484] linearizableReadLoop","detail":"{readStateIndex:2521; appliedIndex:2520; }","duration":"127.432374ms","start":"2026-04-16T11:50:52.460776Z","end":"2026-04-16T11:50:52.588209Z","steps":["trace[104874484] 'read index received' (duration: 127.25032ms)","trace[104874484] 'applied index is now lower than readState.Index' (duration: 180.994µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:50:52.588401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.599437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T11:50:52.588459Z","caller":"traceutil/trace.go:171","msg":"trace[1856155860] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2405; }","duration":"127.69612ms","start":"2026-04-16T11:50:52.460729Z","end":"2026-04-16T11:50:52.588425Z","steps":["trace[1856155860] 'agreement among raft nodes before linearized reading' (duration: 127.590077ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:52.588459Z","caller":"traceutil/trace.go:171","msg":"trace[309289561] transaction","detail":"{read_only:false; response_revision:2405; number_of_response:1; }","duration":"240.975018ms","start":"2026-04-16T11:50:52.347456Z","end":"2026-04-16T11:50:52.588431Z","steps":["trace[309289561] 'process raft request' (duration: 240.59439ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:53.066809Z","caller":"traceutil/trace.go:171","msg":"trace[521348774] transaction","detail":"{read_only:false; response_revision:2407; number_of_response:1; }","duration":"111.960138ms","start":"2026-04-16T11:50:52.954821Z","end":"2026-04-16T11:50:53.066782Z","steps":["trace[521348774] 'process raft request' (duration: 111.807125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:53.25639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.051821ms","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-16T11:50:53.256471Z","caller":"traceutil/trace.go:171","msg":"trace[1402617524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2407; }","duration":"100.158923ms","start":"2026-04-16T11:50:53.156293Z","end":"2026-04-16T11:50:53.256452Z","steps":["trace[1402617524] 'range keys from in-memory index tree' (duration: 99.837036ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:53.723998Z","caller":"traceutil/trace.go:171","msg":"trace[599816319] transaction","detail":"{read_only:false; response_revision:2410; number_of_response:1; }","duration":"175.350794ms","start":"2026-04-16T11:50:53.548618Z","end":"2026-04-16T11:50:53.723968Z","steps":["trace[599816319] 'process raft request' (duration: 82.172392ms)","trace[599816319] 'compare' (duration: 93.06944ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:50:53.940234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.002855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:50:53.940363Z","caller":"traceutil/trace.go:171","msg":"trace[386137388] transaction","detail":"{read_only:false; response_revision:2411; number_of_response:1; }","duration":"161.401562ms","start":"2026-04-16T11:50:53.77894Z","end":"2026-04-16T11:50:53.940341Z","steps":["trace[386137388] 'process raft request' (duration: 52.215313ms)","trace[386137388] 'compare' (duration: 108.836751ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:50:54.409183Z","caller":"traceutil/trace.go:171","msg":"trace[1136041355] linearizableReadLoop","detail":"{readStateIndex:2528; appliedIndex:2527; }","duration":"210.785912ms","start":"2026-04-16T11:50:54.19837Z","end":"2026-04-16T11:50:54.409156Z","steps":["trace[1136041355] 'read index received' (duration: 210.585248ms)","trace[1136041355] 'applied index is now lower than readState.Index' (duration: 199.654µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:50:54.409237Z","caller":"traceutil/trace.go:171","msg":"trace[1257581280] transaction","detail":"{read_only:false; response_revision:2412; number_of_response:1; }","duration":"464.66648ms","start":"2026-04-16T11:50:53.944544Z","end":"2026-04-16T11:50:54.40921Z","steps":["trace[1257581280] 'process raft request' (duration: 464.456106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:54.409403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.026858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-04-16T11:50:54.40943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:50:53.944529Z","time spent":"464.803673ms","remote":"127.0.0.1:59554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:50:54.40946Z","caller":"traceutil/trace.go:171","msg":"trace[1504958304] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2412; }","duration":"211.13013ms","start":"2026-04-16T11:50:54.198318Z","end":"2026-04-16T11:50:54.409448Z","steps":["trace[1504958304] 'agreement among raft nodes before linearized reading' (duration: 210.936166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:54.715287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.051558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:50:54.715389Z","caller":"traceutil/trace.go:171","msg":"trace[329344697] linearizableReadLoop","detail":"{readStateIndex:2529; appliedIndex:2528; }","duration":"122.1638ms","start":"2026-04-16T11:50:54.593208Z","end":"2026-04-16T11:50:54.715372Z","steps":["trace[329344697] 'read index received' (duration: 45.991µs)","trace[329344697] 'applied index is now lower than readState.Index' (duration: 122.116339ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:50:54.715418Z","caller":"traceutil/trace.go:171","msg":"trace[1524281085] transaction","detail":"{read_only:false; response_revision:2413; number_of_response:1; }","duration":"301.951401ms","start":"2026-04-16T11:50:54.413431Z","end":"2026-04-16T11:50:54.715382Z","steps":["trace[1524281085] 'process raft request' (duration: 162.71479ms)","trace[1524281085] 'compare' (duration: 138.732739ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:50:54.715572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:50:54.413412Z","time spent":"302.076363ms","remote":"127.0.0.1:59554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:50:54.71557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.425937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T11:50:54.715594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.383815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-16T11:50:54.715623Z","caller":"traceutil/trace.go:171","msg":"trace[1849307131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2413; }","duration":"111.51188ms","start":"2026-04-16T11:50:54.6041Z","end":"2026-04-16T11:50:54.715612Z","steps":["trace[1849307131] 'agreement among raft nodes before linearized reading' (duration: 111.409107ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:54.715627Z","caller":"traceutil/trace.go:171","msg":"trace[1469005505] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2413; }","duration":"122.457167ms","start":"2026-04-16T11:50:54.593161Z","end":"2026-04-16T11:50:54.715618Z","steps":["trace[1469005505] 'agreement among raft nodes before linearized reading' (duration: 122.310954ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:54.926916Z","caller":"traceutil/trace.go:171","msg":"trace[2034962157] transaction","detail":"{read_only:false; response_revision:2415; number_of_response:1; }","duration":"206.160142ms","start":"2026-04-16T11:50:54.720731Z","end":"2026-04-16T11:50:54.926891Z","steps":["trace[2034962157] 'process raft request' (duration: 175.484057ms)","trace[2034962157] 'compare' (duration: 30.288837ms)"],"step_count":2}